[INFO] cloning repository https://github.com/Bazooh/RustLox
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/Bazooh/RustLox" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2FBazooh%2FRustLox", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2FBazooh%2FRustLox'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] f0dfc0cf917f5997c94a6cb5556b8b9c354da810
[INFO] testing Bazooh/RustLox against try#b8e88e5ddf5521a9f43ee3f62a702388c713e4bb for pr-155114
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2FBazooh%2FRustLox" "/workspace/builds/worker-1-tc2/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-1-tc2/source'...
[INFO] [stderr] done.
[INFO] started tweaking git repo https://github.com/Bazooh/RustLox
[INFO] finished tweaking git repo https://github.com/Bazooh/RustLox
[INFO] tweaked toml for git repo https://github.com/Bazooh/RustLox written to /workspace/builds/worker-1-tc2/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/Bazooh/RustLox on toolchain b8e88e5ddf5521a9f43ee3f62a702388c713e4bb
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+b8e88e5ddf5521a9f43ee3f62a702388c713e4bb" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate git repo https://github.com/Bazooh/RustLox already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+b8e88e5ddf5521a9f43ee3f62a702388c713e4bb" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+b8e88e5ddf5521a9f43ee3f62a702388c713e4bb" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 4af8b74c421a92b6ad45795f91e324f50d204f1d2c8f0ce4d778749c046da97d
[INFO] running `Command { std: "docker" "start" "-a" "4af8b74c421a92b6ad45795f91e324f50d204f1d2c8f0ce4d778749c046da97d", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "4af8b74c421a92b6ad45795f91e324f50d204f1d2c8f0ce4d778749c046da97d", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "4af8b74c421a92b6ad45795f91e324f50d204f1d2c8f0ce4d778749c046da97d", kill_on_drop: false }`
[INFO] [stdout] 4af8b74c421a92b6ad45795f91e324f50d204f1d2c8f0ce4d778749c046da97d
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+b8e88e5ddf5521a9f43ee3f62a702388c713e4bb" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] f5d392dc1f057500b8b8da0a1556f05a1940222271562bfea5f726ec6b726f1a
[INFO] running `Command { std: "docker" "start" "-a" "f5d392dc1f057500b8b8da0a1556f05a1940222271562bfea5f726ec6b726f1a", kill_on_drop: false }`
[INFO] [stderr]    Compiling proc-macro2 v1.0.104
[INFO] [stderr]    Compiling quote v1.0.42
[INFO] [stderr]    Compiling unicode-ident v1.0.22
[INFO] [stderr]    Compiling winnow v0.7.14
[INFO] [stderr]    Compiling memchr v2.7.6
[INFO] [stderr]    Compiling equivalent v1.0.2
[INFO] [stderr]    Compiling toml_datetime v0.7.5+spec-1.1.0
[INFO] [stderr]    Compiling regex-syntax v0.8.8
[INFO] [stderr]    Compiling indexmap v2.13.0
[INFO] [stderr]    Compiling rstest_macros v0.26.1
[INFO] [stderr]    Compiling pin-project-lite v0.2.16
[INFO] [stderr]    Compiling glob v0.3.3
[INFO] [stderr]    Compiling relative-path v1.9.3
[INFO] [stderr]    Compiling futures-core v0.3.31
[INFO] [stderr]    Compiling slab v0.4.11
[INFO] [stderr]    Compiling cfg-if v1.0.4
[INFO] [stderr]    Compiling futures-timer v3.0.3
[INFO] [stderr]    Compiling strum v0.27.2
[INFO] [stderr]    Compiling aho-corasick v1.1.4
[INFO] [stderr]    Compiling syn v2.0.112
[INFO] [stderr]    Compiling toml_parser v1.0.6+spec-1.1.0
[INFO] [stderr]    Compiling regex-automata v0.4.13
[INFO] [stderr]    Compiling toml_edit v0.23.10+spec-1.0.0
[INFO] [stderr]    Compiling proc-macro-crate v3.4.0
[INFO] [stderr]    Compiling regex v1.12.3
[INFO] [stderr]    Compiling futures-macro v0.3.31
[INFO] [stderr]    Compiling strum_macros v0.27.2
[INFO] [stderr]    Compiling futures-util v0.3.31
[INFO] [stderr]    Compiling rstest v0.26.1
[INFO] [stderr]    Compiling RustLox v0.1.0 (/opt/rustwide/workdir)
[INFO] [stdout] warning: method `repr` is never used
[INFO] [stdout]   --> src/instruction.rs:96:12
[INFO] [stdout]    |
[INFO] [stdout] 95 | impl Instruction {
[INFO] [stdout]    | ---------------- method in this implementation
[INFO] [stdout] 96 |     pub fn repr(&self) -> String {
[INFO] [stdout]    |            ^^^^
[INFO] [stdout]    |
[INFO] [stdout]    = note: `#[warn(dead_code)]` (part of `#[warn(unused)]`) on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: function `parse_expect` is never used
[INFO] [stdout]  --> src/test.rs:6:4
[INFO] [stdout]   |
[INFO] [stdout] 6 | fn parse_expect(content: &str) -> (String, String) {
[INFO] [stdout]   |    ^^^^^^^^^^^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: function `test_file` is never used
[INFO] [stdout]   --> src/test.rs:35:4
[INFO] [stdout]    |
[INFO] [stdout] 35 | fn test_file(path: &str, func: fn(&str) -> (String, String)) {
[INFO] [stdout]    |    ^^^^^^^^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: function `test_interpret_file` is never used
[INFO] [stdout]   --> src/test.rs:91:4
[INFO] [stdout]    |
[INFO] [stdout] 91 | fn test_interpret_file(path: &str) {
[INFO] [stdout]    |    ^^^^^^^^^^^^^^^^^^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: function `test_tokenize_file` is never used
[INFO] [stdout]    --> src/test.rs:108:4
[INFO] [stdout]     |
[INFO] [stdout] 108 | fn test_tokenize_file(path: &str) {
[INFO] [stdout]     |    ^^^^^^^^^^^^^^^^^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 38.62s
[INFO] running `Command { std: "docker" "inspect" "f5d392dc1f057500b8b8da0a1556f05a1940222271562bfea5f726ec6b726f1a", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "f5d392dc1f057500b8b8da0a1556f05a1940222271562bfea5f726ec6b726f1a", kill_on_drop: false }`
[INFO] [stdout] f5d392dc1f057500b8b8da0a1556f05a1940222271562bfea5f726ec6b726f1a
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+b8e88e5ddf5521a9f43ee3f62a702388c713e4bb" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 0d71f5eb17c0d740e251e7b4893c2814f16165de9bf4587a42b221a5bff7327c
[INFO] running `Command { std: "docker" "start" "-a" "0d71f5eb17c0d740e251e7b4893c2814f16165de9bf4587a42b221a5bff7327c", kill_on_drop: false }`
[INFO] [stderr]    Compiling RustLox v0.1.0 (/opt/rustwide/workdir)
[INFO] [stdout] warning: method `repr` is never used
[INFO] [stdout]   --> src/instruction.rs:96:12
[INFO] [stdout]    |
[INFO] [stdout] 95 | impl Instruction {
[INFO] [stdout]    | ---------------- method in this implementation
[INFO] [stdout] 96 |     pub fn repr(&self) -> String {
[INFO] [stdout]    |            ^^^^
[INFO] [stdout]    |
[INFO] [stdout]    = note: `#[warn(dead_code)]` (part of `#[warn(unused)]`) on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 2.30s
[INFO] running `Command { std: "docker" "inspect" "0d71f5eb17c0d740e251e7b4893c2814f16165de9bf4587a42b221a5bff7327c", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "0d71f5eb17c0d740e251e7b4893c2814f16165de9bf4587a42b221a5bff7327c", kill_on_drop: false }`
[INFO] [stdout] 0d71f5eb17c0d740e251e7b4893c2814f16165de9bf4587a42b221a5bff7327c
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+b8e88e5ddf5521a9f43ee3f62a702388c713e4bb" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 62cc9cb0f52734682a101d97b57e25d670fa0d2c890db629952fa9070a0a7553
[INFO] running `Command { std: "docker" "start" "-a" "62cc9cb0f52734682a101d97b57e25d670fa0d2c890db629952fa9070a0a7553", kill_on_drop: false }`
[INFO] [stderr] warning: method `repr` is never used
[INFO] [stderr]   --> src/instruction.rs:96:12
[INFO] [stderr]    |
[INFO] [stderr] 95 | impl Instruction {
[INFO] [stderr]    | ---------------- method in this implementation
[INFO] [stderr] 96 |     pub fn repr(&self) -> String {
[INFO] [stderr]    |            ^^^^
[INFO] [stderr]    |
[INFO] [stderr]    = note: `#[warn(dead_code)]` (part of `#[warn(unused)]`) on by default
[INFO] [stderr] 
[INFO] [stderr] warning: `RustLox` (bin "RustLox" test) generated 1 warning
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.08s
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/deps/RustLox-a3bc338cdf622158)
[INFO] [stdout] 
[INFO] [stdout] running 31 tests
[INFO] [stdout] test test::test_empty_file ... ok
[INFO] [stdout] test test::test_module::case_03_bool ... ok
[INFO] [stdout] test test::test_module::case_09_expressions ... ok
[INFO] [stdout] test test::test_module::case_01_assignment ... ok
[INFO] [stdout] test test::test_module::case_02_block ... ok
[INFO] [stdout] test test::test_module::case_07_comments ... ok
[INFO] [stdout] test test::test_module::case_08_constructor ... FAILED
[INFO] [stdout] test test::test_module::case_05_class ... FAILED
[INFO] [stdout] test test::test_module::case_04_call ... ok
[INFO] [stdout] test test::test_module::case_15_logical_operator ... ok
[INFO] [stdout] test test::test_module::case_17_nil ... ok
[INFO] [stdout] test test::test_module::case_11_for_ ... ok
[INFO] [stdout] test test::test_module::case_06_closure ... ok
[INFO] [stdout] test test::test_module::case_13_if_ ... ok
[INFO] [stdout] test test::test_module::case_20_print ... ok
[INFO] [stdout] test test::test_module::case_18_number ... ok
[INFO] [stdout] test test::test_module::case_21_regression ... ok
[INFO] [stdout] test test::test_module::case_16_method ... ok
[INFO] [stdout] test test::test_module::case_24_super ... FAILED
[INFO] [stdout] test test::test_module::case_23_string ... ok
[INFO] [stdout] test test::test_module::case_14_inheritance ... FAILED
[INFO] [stdout] test test::test_module::case_25_this ... FAILED
[INFO] [stdout] test test::test_precedence ... ok
[INFO] [stdout] test test::test_unexpected_character ... ok
[INFO] [stdout] test test::test_module::case_22_return_ ... ok
[INFO] [stdout] test test::test_tokenize::case_1 ... ok
[INFO] [stdout] test test::test_module::case_27_while_ ... ok
[INFO] [stdout] test test::test_module::case_12_function ... ok
[INFO] [stdout] test test::test_module::case_26_variable ... ok
[INFO] [stdout] test test::test_module::case_19_operator ... ok
[INFO] [stdout] test test::test_module::case_10_field ... ok
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- test::test_module::case_08_constructor stdout ----
[INFO] [stdout] [
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 2,
[INFO] [stdout]         superclass: None,
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 9,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 2,
[INFO] [stdout]     },
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 2,
[INFO] [stdout]     },
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 3,
[INFO] [stdout]     },
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 3,
[INFO] [stdout]     },
[INFO] [stdout]     Print,
[INFO] [stdout]     Exit {
[INFO] [stdout]         code: 0,
[INFO] [stdout]     },
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 0,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     String(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     Print,
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     Return,
[INFO] [stdout]     String(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Print,
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     Return,
[INFO] [stdout] ]
[INFO] [stdout] Executing byte at 0: Class { name_index: 2, superclass: None } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: []
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 2: CreateGlobal { string_index: 2 } Stack: ["<class Foo>"]
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn init>", "<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 3: GetGlobal { string_index: 2 } Stack: []
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn init>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 4: Call { arg_count: 0 } Stack: ["<class Foo>"]
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn init>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 10: String(0) Stack: []
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn init>"]
[INFO] [stdout] FREEING: ["<Foo instance>", "<fn init>"]
[INFO] [stdout] Executing byte at 11: Print Stack: ["init"]
[INFO] [stdout] GC: checking value "init"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "init", "<fn init>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 12: GetCaptured(0) Stack: []
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn init>"]
[INFO] [stdout] FREEING: ["init"]
[INFO] [stdout] 
[INFO] [stdout] thread 'test::test_module::case_08_constructor' (26) panicked at src/heap.rs:121:14:
[INFO] [stdout] Use after free: invalid address
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5fe39a7cb55a - std[fce30c26ee6459b]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x5fe39a7cb55a - std[fce30c26ee6459b]::backtrace_rs::backtrace::trace_unsynchronized::<std[fce30c26ee6459b]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x5fe39a7cb55a - std[fce30c26ee6459b]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x5fe39a7cb55a - <<std[fce30c26ee6459b]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[d17f6591d688f3bd]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x5fe39a7e272a - <core[d17f6591d688f3bd]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x5fe39a7e272a - core[d17f6591d688f3bd]::fmt::write
[INFO] [stdout]    6:     0x5fe39a7cffe2 - std[fce30c26ee6459b]::io::default_write_fmt::<alloc[dad09f3a48280dba]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/io/mod.rs:621:11
[INFO] [stdout]    7:     0x5fe39a7cffe2 - <alloc[dad09f3a48280dba]::vec::Vec<u8> as std[fce30c26ee6459b]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/io/mod.rs:1976:13
[INFO] [stdout]    8:     0x5fe39a7a9d6f - <std[fce30c26ee6459b]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x5fe39a7a9d6f - std[fce30c26ee6459b]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x5fe39a7c3409 - std[fce30c26ee6459b]::panicking::default_hook
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x5fe39a4e710c - <alloc[dad09f3a48280dba]::boxed::Box<dyn for<'a, 'b> core[d17f6591d688f3bd]::ops::function::Fn<(&'a std[fce30c26ee6459b]::panic::PanicHookInfo<'b>,), Output = ()> + core[d17f6591d688f3bd]::marker::Sync + core[d17f6591d688f3bd]::marker::Send> as core[d17f6591d688f3bd]::ops::function::Fn<(&std[fce30c26ee6459b]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/alloc/src/boxed.rs:2285:9
[INFO] [stdout]   12:     0x5fe39a4e710c - test[9653f4f1d2ca1489]::test_main_inner::<test[9653f4f1d2ca1489]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:155:21
[INFO] [stdout]   13:     0x5fe39a7c35c2 - <alloc[dad09f3a48280dba]::boxed::Box<dyn for<'a, 'b> core[d17f6591d688f3bd]::ops::function::Fn<(&'a std[fce30c26ee6459b]::panic::PanicHookInfo<'b>,), Output = ()> + core[d17f6591d688f3bd]::marker::Sync + core[d17f6591d688f3bd]::marker::Send> as core[d17f6591d688f3bd]::ops::function::Fn<(&std[fce30c26ee6459b]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/alloc/src/boxed.rs:2285:9
[INFO] [stdout]   14:     0x5fe39a7c35c2 - std[fce30c26ee6459b]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x5fe39a7a9e28 - std[fce30c26ee6459b]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:698:13
[INFO] [stdout]   16:     0x5fe39a79ee59 - std[fce30c26ee6459b]::sys::backtrace::__rust_end_short_backtrace::<std[fce30c26ee6459b]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x5fe39a7aab3d - __rustc[de152adb51f02556]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x5fe39a7e2f6c - core[d17f6591d688f3bd]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x5fe39a7e2c64 - core[d17f6591d688f3bd]::panicking::panic_display::<&str>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/panicking.rs:259:5
[INFO] [stdout]   20:     0x5fe39a7e2c64 - core[d17f6591d688f3bd]::option::expect_failed
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/option.rs:2260:5
[INFO] [stdout]   21:     0x5fe39a4ae5c0 - <core[d17f6591d688f3bd]::option::Option<&RustLox[3daf0c3e01451a46]::heap::HeapValue>>::expect
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/option.rs:971:21
[INFO] [stdout]   22:     0x5fe39a4cdb2e - <RustLox[3daf0c3e01451a46]::heap::Heap>::get
[INFO] [stdout]                                at /opt/rustwide/workdir/src/heap.rs:121:14
[INFO] [stdout]   23:     0x5fe39a49cbdd - <RustLox[3daf0c3e01451a46]::value::Value>::deref
[INFO] [stdout]                                at /opt/rustwide/workdir/src/value.rs:103:51
[INFO] [stdout]   24:     0x5fe39a49f36a - <RustLox[3daf0c3e01451a46]::interpreter::Interpreter<&mut alloc[dad09f3a48280dba]::string::String, &mut alloc[dad09f3a48280dba]::string::String>>::get_captured
[INFO] [stdout]                                at /opt/rustwide/workdir/src/interpreter.rs:188:42
[INFO] [stdout]   25:     0x5fe39a4a1972 - <RustLox[3daf0c3e01451a46]::interpreter::Interpreter<&mut alloc[dad09f3a48280dba]::string::String, &mut alloc[dad09f3a48280dba]::string::String>>::interpret_unsafe
[INFO] [stdout]                                at /opt/rustwide/workdir/src/interpreter.rs:664:38
[INFO] [stdout]   26:     0x5fe39a4abdda - <RustLox[3daf0c3e01451a46]::interpreter::Interpreter<&mut alloc[dad09f3a48280dba]::string::String, &mut alloc[dad09f3a48280dba]::string::String>>::interpret
[INFO] [stdout]                                at /opt/rustwide/workdir/src/interpreter.rs:866:20
[INFO] [stdout]   27:     0x5fe39a4c3a36 - RustLox[3daf0c3e01451a46]::test::test_interpret_file::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:100:29
[INFO] [stdout]   28:     0x5fe39a4c863a - <RustLox[3daf0c3e01451a46]::test::test_interpret_file::{closure#0} as core[d17f6591d688f3bd]::ops::function::FnOnce<(&str,)>>::call_once
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   29:     0x5fe39a4c6321 - RustLox[3daf0c3e01451a46]::test::test_file
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:38:18
[INFO] [stdout]   30:     0x5fe39a4c60aa - RustLox[3daf0c3e01451a46]::test::test_interpret_file
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:92:5
[INFO] [stdout]   31:     0x5fe39a4c4fe6 - RustLox[3daf0c3e01451a46]::test::test_module
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:160:9
[INFO] [stdout]   32:     0x5fe39a4ce312 - RustLox[3daf0c3e01451a46]::test::test_module::case_08_constructor
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:125:1
[INFO] [stdout]   33:     0x5fe39a4ca967 - RustLox[3daf0c3e01451a46]::test::test_module::case_08_constructor::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:125:10
[INFO] [stdout]   34:     0x5fe39a4c8c76 - <RustLox[3daf0c3e01451a46]::test::test_module::case_08_constructor::{closure#0} as core[d17f6591d688f3bd]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   35:     0x5fe39a4da3eb - <fn() -> core[d17f6591d688f3bd]::result::Result<(), alloc[dad09f3a48280dba]::string::String> as core[d17f6591d688f3bd]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   36:     0x5fe39a4da3eb - test[9653f4f1d2ca1489]::__rust_begin_short_backtrace::<core[d17f6591d688f3bd]::result::Result<(), alloc[dad09f3a48280dba]::string::String>, fn() -> core[d17f6591d688f3bd]::result::Result<(), alloc[dad09f3a48280dba]::string::String>>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:724:18
[INFO] [stdout]   37:     0x5fe39a4e7bdb - test[9653f4f1d2ca1489]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:747:74
[INFO] [stdout]   38:     0x5fe39a4e7bdb - <core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<test[9653f4f1d2ca1489]::run_test_in_process::{closure#0}> as core[d17f6591d688f3bd]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   39:     0x5fe39a4e7bdb - std[fce30c26ee6459b]::panicking::catch_unwind::do_call::<core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<test[9653f4f1d2ca1489]::run_test_in_process::{closure#0}>, core[d17f6591d688f3bd]::result::Result<(), alloc[dad09f3a48280dba]::string::String>>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:581:40
[INFO] [stdout]   40:     0x5fe39a4e7bdb - std[fce30c26ee6459b]::panicking::catch_unwind::<core[d17f6591d688f3bd]::result::Result<(), alloc[dad09f3a48280dba]::string::String>, core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<test[9653f4f1d2ca1489]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:544:19
[INFO] [stdout]   41:     0x5fe39a4e7bdb - std[fce30c26ee6459b]::panic::catch_unwind::<core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<test[9653f4f1d2ca1489]::run_test_in_process::{closure#0}>, core[d17f6591d688f3bd]::result::Result<(), alloc[dad09f3a48280dba]::string::String>>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panic.rs:359:14
[INFO] [stdout]   42:     0x5fe39a4e7bdb - test[9653f4f1d2ca1489]::run_test_in_process
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:747:27
[INFO] [stdout]   43:     0x5fe39a4e7bdb - test[9653f4f1d2ca1489]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:668:43
[INFO] [stdout]   44:     0x5fe39a4e12f4 - test[9653f4f1d2ca1489]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:698:41
[INFO] [stdout]   45:     0x5fe39a4e12f4 - std[fce30c26ee6459b]::sys::backtrace::__rust_begin_short_backtrace::<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   46:     0x5fe39a4ea7e2 - std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked::<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   47:     0x5fe39a4ea7e2 - <core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[d17f6591d688f3bd]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   48:     0x5fe39a4ea7e2 - std[fce30c26ee6459b]::panicking::catch_unwind::do_call::<core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:581:40
[INFO] [stdout]   49:     0x5fe39a4ea7e2 - std[fce30c26ee6459b]::panicking::catch_unwind::<(), core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:544:19
[INFO] [stdout]   50:     0x5fe39a4ea7e2 - std[fce30c26ee6459b]::panic::catch_unwind::<core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panic.rs:359:14
[INFO] [stdout]   51:     0x5fe39a4ea7e2 - std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked::<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   52:     0x5fe39a4ea7e2 - <std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1} as core[d17f6591d688f3bd]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   53:     0x5fe39a7cab0f - <alloc[dad09f3a48280dba]::boxed::Box<dyn core[d17f6591d688f3bd]::ops::function::FnOnce<(), Output = ()> + core[d17f6591d688f3bd]::marker::Send> as core[d17f6591d688f3bd]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/alloc/src/boxed.rs:2271:9
[INFO] [stdout]   54:     0x5fe39a7cab0f - <std[fce30c26ee6459b]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   55:     0x74feac48baa4 - <unknown>
[INFO] [stdout]   56:     0x74feac518a64 - clone
[INFO] [stdout]   57:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- test::test_module::case_05_class stdout ----
[INFO] [stdout] [
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 0,
[INFO] [stdout]         superclass: None,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 0,
[INFO] [stdout]     },
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 1,
[INFO] [stdout]     },
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 1,
[INFO] [stdout]     },
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Exit {
[INFO] [stdout]         code: 0,
[INFO] [stdout]     },
[INFO] [stdout] ]
[INFO] [stdout] Executing byte at 0: Class { name_index: 0, superclass: None } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: []
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 1: CreateGlobal { string_index: 0 } Stack: ["<class Foo>"]
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 2: GetGlobal { string_index: 0 } Stack: []
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 3: Call { arg_count: 0 } Stack: ["<class Foo>"]
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 4: CreateGlobal { string_index: 1 } Stack: ["<Foo instance>"]
[INFO] [stdout] GC: checking value "<Foo instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<Foo instance>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 5: GetGlobal { string_index: 1 } Stack: []
[INFO] [stdout] GC: checking value "<Foo instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<Foo instance>", "<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 6: Call { arg_count: 0 } Stack: ["<Foo instance>"]
[INFO] [stdout] GC: checking value "<Foo instance>"
[INFO] [stdout] GC: checking value "<Foo instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<Foo instance>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] [
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 1,
[INFO] [stdout]         superclass: None,
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 9,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 1,
[INFO] [stdout]     },
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 1,
[INFO] [stdout]     },
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Print,
[INFO] [stdout]     Exit {
[INFO] [stdout]         code: 0,
[INFO] [stdout]     },
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 0,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 1,
[INFO] [stdout]     },
[INFO] [stdout]     Return,
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout] ]
[INFO] [stdout] Executing byte at 0: Class { name_index: 1, superclass: None } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: []
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 2: CreateGlobal { string_index: 1 } Stack: ["<class Foo>"]
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn returnSelf>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 3: GetGlobal { string_index: 1 } Stack: []
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn returnSelf>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 4: Call { arg_count: 0 } Stack: ["<class Foo>"]
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn returnSelf>", "<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 5: DotProperty(0) Stack: ["<Foo instance>"]
[INFO] [stdout] GC: checking value "<Foo instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn returnSelf>", "<Foo instance>", "<fn returnSelf>", "<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 6: Call { arg_count: 0 } Stack: ["<fn returnSelf>"]
[INFO] [stdout] GC: checking value "<fn returnSelf>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn returnSelf>", "<Foo instance>", "<fn returnSelf>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 10: GetGlobal { string_index: 1 } Stack: []
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn returnSelf>", "<class Foo>"]
[INFO] [stdout] FREEING: ["<Foo instance>", "<fn returnSelf>"]
[INFO] [stdout] Executing byte at 11: Return Stack: ["<class Foo>"]
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn returnSelf>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 7: Print Stack: ["<class Foo>"]
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn returnSelf>", "<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 8: Exit { code: 0 } Stack: []
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn returnSelf>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] [
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 1,
[INFO] [stdout]         superclass: None,
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 9,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     GetLocal(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Print,
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Exit {
[INFO] [stdout]         code: 0,
[INFO] [stdout]     },
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 0,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Capture(
[INFO] [stdout]         Local(
[INFO] [stdout]             0,
[INFO] [stdout]         ),
[INFO] [stdout]     ),
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         2,
[INFO] [stdout]     ),
[INFO] [stdout]     Return,
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout] ]
[INFO] [stdout] Executing byte at 0: Class { name_index: 1, superclass: None } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: []
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 2: GetLocal(0) Stack: ["&<class Foo>"]
[INFO] [stdout] GC: checking value "&<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["&<pointer 1>", "<class Foo>", "<fn returnSelf>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 3: Call { arg_count: 0 } Stack: ["&<class Foo>", "<class Foo>"]
[INFO] [stdout] GC: checking value "&<class Foo>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn returnSelf>", "&<pointer 1>", "<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 4: DotProperty(0) Stack: ["&<class Foo>", "<Foo instance>"]
[INFO] [stdout] GC: checking value "&<class Foo>"
[INFO] [stdout] GC: checking value "<Foo instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn returnSelf>", "<fn returnSelf>", "&<pointer 1>", "<Foo instance>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 5: Call { arg_count: 0 } Stack: ["&<class Foo>", "<fn returnSelf>"]
[INFO] [stdout] GC: checking value "&<class Foo>"
[INFO] [stdout] GC: checking value "<fn returnSelf>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn returnSelf>", "<fn returnSelf>", "&<pointer 1>", "<Foo instance>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 10: Capture(Local(0)) Stack: ["&<class Foo>"]
[INFO] [stdout] GC: checking value "&<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["&<pointer 1>", "<fn returnSelf>", "<class Foo>"]
[INFO] [stdout] FREEING: ["<Foo instance>", "<fn returnSelf>"]
[INFO] [stdout] Executing byte at 11: GetCaptured(2) Stack: ["&<class Foo>"]
[INFO] [stdout] GC: checking value "&<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["&<pointer 1>", "<class Foo>", "<fn returnSelf>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 12: Return Stack: ["&<class Foo>", "<class Foo>"]
[INFO] [stdout] GC: checking value "&<class Foo>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["&<pointer 1>", "<fn returnSelf>", "<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 6: Print Stack: ["&<class Foo>", "<class Foo>"]
[INFO] [stdout] GC: checking value "&<class Foo>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn returnSelf>", "&<pointer 1>", "<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 7: Pop(1) Stack: ["&<class Foo>"]
[INFO] [stdout] GC: checking value "&<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn returnSelf>", "&<pointer 1>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 8: Exit { code: 0 } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: []
[INFO] [stdout] FREEING: ["<class Foo>", "<fn returnSelf>", "&<pointer 1>"]
[INFO] [stdout] [
[INFO] [stdout]     Constant(
[INFO] [stdout]         0.0,
[INFO] [stdout]     ),
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 4,
[INFO] [stdout]         superclass: None,
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 25,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 31,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 4,
[INFO] [stdout]     },
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 4,
[INFO] [stdout]     },
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 5,
[INFO] [stdout]     },
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 5,
[INFO] [stdout]     },
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 4,
[INFO] [stdout]     },
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 6,
[INFO] [stdout]     },
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 6,
[INFO] [stdout]     },
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 5,
[INFO] [stdout]     },
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Exit {
[INFO] [stdout]         code: 0,
[INFO] [stdout]     },
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 1,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         2,
[INFO] [stdout]     ),
[INFO] [stdout]     Print,
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 3,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 0,
[INFO] [stdout]     },
[INFO] [stdout]     AssignField {
[INFO] [stdout]         field_name_index: 2,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Constant(
[INFO] [stdout]         1.0,
[INFO] [stdout]     ),
[INFO] [stdout]     Add,
[INFO] [stdout]     AssignGlobal {
[INFO] [stdout]         string_index: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     Return,
[INFO] [stdout] ]
[INFO] [stdout] Executing byte at 0: Constant(0.0) Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: []
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 1: CreateGlobal { string_index: 0 } Stack: ["0"]
[INFO] [stdout] GC: checking value "0"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: []
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 2: Class { name_index: 4, superclass: None } Stack: []
[INFO] [stdout] GC: checking value "0"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: []
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 5: CreateGlobal { string_index: 4 } Stack: ["<class A>"]
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: checking value "0"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn print_id>", "<class A>", "<fn init>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 6: GetGlobal { string_index: 4 } Stack: []
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: checking value "0"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn print_id>", "<fn init>", "<class A>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 7: Call { arg_count: 0 } Stack: ["<class A>"]
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: checking value "0"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class A>", "<fn print_id>", "<fn init>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 32: GetCaptured(0) Stack: []
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: checking value "0"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn init>", "<fn print_id>", "<class A>"]
[INFO] [stdout] FREEING: ["<fn init>", "<A instance>", "<fn print_id>"]
[INFO] [stdout] 
[INFO] [stdout] thread 'test::test_module::case_05_class' (23) panicked at src/heap.rs:121:14:
[INFO] [stdout] Use after free: invalid address
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5fe39a7cb55a - std[fce30c26ee6459b]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x5fe39a7cb55a - std[fce30c26ee6459b]::backtrace_rs::backtrace::trace_unsynchronized::<std[fce30c26ee6459b]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x5fe39a7cb55a - std[fce30c26ee6459b]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x5fe39a7cb55a - <<std[fce30c26ee6459b]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[d17f6591d688f3bd]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x5fe39a7e272a - <core[d17f6591d688f3bd]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x5fe39a7e272a - core[d17f6591d688f3bd]::fmt::write
[INFO] [stdout]    6:     0x5fe39a7cffe2 - std[fce30c26ee6459b]::io::default_write_fmt::<alloc[dad09f3a48280dba]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/io/mod.rs:621:11
[INFO] [stdout]    7:     0x5fe39a7cffe2 - <alloc[dad09f3a48280dba]::vec::Vec<u8> as std[fce30c26ee6459b]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/io/mod.rs:1976:13
[INFO] [stdout]    8:     0x5fe39a7a9d6f - <std[fce30c26ee6459b]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x5fe39a7a9d6f - std[fce30c26ee6459b]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x5fe39a7c3409 - std[fce30c26ee6459b]::panicking::default_hook
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x5fe39a4e710c - <alloc[dad09f3a48280dba]::boxed::Box<dyn for<'a, 'b> core[d17f6591d688f3bd]::ops::function::Fn<(&'a std[fce30c26ee6459b]::panic::PanicHookInfo<'b>,), Output = ()> + core[d17f6591d688f3bd]::marker::Sync + core[d17f6591d688f3bd]::marker::Send> as core[d17f6591d688f3bd]::ops::function::Fn<(&std[fce30c26ee6459b]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/alloc/src/boxed.rs:2285:9
[INFO] [stdout]   12:     0x5fe39a4e710c - test[9653f4f1d2ca1489]::test_main_inner::<test[9653f4f1d2ca1489]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:155:21
[INFO] [stdout]   13:     0x5fe39a7c35c2 - <alloc[dad09f3a48280dba]::boxed::Box<dyn for<'a, 'b> core[d17f6591d688f3bd]::ops::function::Fn<(&'a std[fce30c26ee6459b]::panic::PanicHookInfo<'b>,), Output = ()> + core[d17f6591d688f3bd]::marker::Sync + core[d17f6591d688f3bd]::marker::Send> as core[d17f6591d688f3bd]::ops::function::Fn<(&std[fce30c26ee6459b]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/alloc/src/boxed.rs:2285:9
[INFO] [stdout]   14:     0x5fe39a7c35c2 - std[fce30c26ee6459b]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x5fe39a7a9e28 - std[fce30c26ee6459b]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:698:13
[INFO] [stdout]   16:     0x5fe39a79ee59 - std[fce30c26ee6459b]::sys::backtrace::__rust_end_short_backtrace::<std[fce30c26ee6459b]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x5fe39a7aab3d - __rustc[de152adb51f02556]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x5fe39a7e2f6c - core[d17f6591d688f3bd]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x5fe39a7e2c64 - core[d17f6591d688f3bd]::panicking::panic_display::<&str>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/panicking.rs:259:5
[INFO] [stdout]   20:     0x5fe39a7e2c64 - core[d17f6591d688f3bd]::option::expect_failed
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/option.rs:2260:5
[INFO] [stdout]   21:     0x5fe39a4ae5c0 - <core[d17f6591d688f3bd]::option::Option<&RustLox[3daf0c3e01451a46]::heap::HeapValue>>::expect
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/option.rs:971:21
[INFO] [stdout]   22:     0x5fe39a4cdb2e - <RustLox[3daf0c3e01451a46]::heap::Heap>::get
[INFO] [stdout]                                at /opt/rustwide/workdir/src/heap.rs:121:14
[INFO] [stdout]   23:     0x5fe39a49cbdd - <RustLox[3daf0c3e01451a46]::value::Value>::deref
[INFO] [stdout]                                at /opt/rustwide/workdir/src/value.rs:103:51
[INFO] [stdout]   24:     0x5fe39a49f36a - <RustLox[3daf0c3e01451a46]::interpreter::Interpreter<&mut alloc[dad09f3a48280dba]::string::String, &mut alloc[dad09f3a48280dba]::string::String>>::get_captured
[INFO] [stdout]                                at /opt/rustwide/workdir/src/interpreter.rs:188:42
[INFO] [stdout]   25:     0x5fe39a4a1972 - <RustLox[3daf0c3e01451a46]::interpreter::Interpreter<&mut alloc[dad09f3a48280dba]::string::String, &mut alloc[dad09f3a48280dba]::string::String>>::interpret_unsafe
[INFO] [stdout]                                at /opt/rustwide/workdir/src/interpreter.rs:664:38
[INFO] [stdout]   26:     0x5fe39a4abdda - <RustLox[3daf0c3e01451a46]::interpreter::Interpreter<&mut alloc[dad09f3a48280dba]::string::String, &mut alloc[dad09f3a48280dba]::string::String>>::interpret
[INFO] [stdout]                                at /opt/rustwide/workdir/src/interpreter.rs:866:20
[INFO] [stdout]   27:     0x5fe39a4c3a36 - RustLox[3daf0c3e01451a46]::test::test_interpret_file::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:100:29
[INFO] [stdout]   28:     0x5fe39a4c863a - <RustLox[3daf0c3e01451a46]::test::test_interpret_file::{closure#0} as core[d17f6591d688f3bd]::ops::function::FnOnce<(&str,)>>::call_once
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   29:     0x5fe39a4c6321 - RustLox[3daf0c3e01451a46]::test::test_file
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:38:18
[INFO] [stdout]   30:     0x5fe39a4c60aa - RustLox[3daf0c3e01451a46]::test::test_interpret_file
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:92:5
[INFO] [stdout]   31:     0x5fe39a4c4fe6 - RustLox[3daf0c3e01451a46]::test::test_module
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:160:9
[INFO] [stdout]   32:     0x5fe39a4cdf12 - RustLox[3daf0c3e01451a46]::test::test_module::case_05_class
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:125:1
[INFO] [stdout]   33:     0x5fe39a4ca667 - RustLox[3daf0c3e01451a46]::test::test_module::case_05_class::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:125:10
[INFO] [stdout]   34:     0x5fe39a4c8876 - <RustLox[3daf0c3e01451a46]::test::test_module::case_05_class::{closure#0} as core[d17f6591d688f3bd]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   35:     0x5fe39a4da3eb - <fn() -> core[d17f6591d688f3bd]::result::Result<(), alloc[dad09f3a48280dba]::string::String> as core[d17f6591d688f3bd]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   36:     0x5fe39a4da3eb - test[9653f4f1d2ca1489]::__rust_begin_short_backtrace::<core[d17f6591d688f3bd]::result::Result<(), alloc[dad09f3a48280dba]::string::String>, fn() -> core[d17f6591d688f3bd]::result::Result<(), alloc[dad09f3a48280dba]::string::String>>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:724:18
[INFO] [stdout]   37:     0x5fe39a4e7bdb - test[9653f4f1d2ca1489]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:747:74
[INFO] [stdout]   38:     0x5fe39a4e7bdb - <core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<test[9653f4f1d2ca1489]::run_test_in_process::{closure#0}> as core[d17f6591d688f3bd]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   39:     0x5fe39a4e7bdb - std[fce30c26ee6459b]::panicking::catch_unwind::do_call::<core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<test[9653f4f1d2ca1489]::run_test_in_process::{closure#0}>, core[d17f6591d688f3bd]::result::Result<(), alloc[dad09f3a48280dba]::string::String>>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:581:40
[INFO] [stdout]   40:     0x5fe39a4e7bdb - std[fce30c26ee6459b]::panicking::catch_unwind::<core[d17f6591d688f3bd]::result::Result<(), alloc[dad09f3a48280dba]::string::String>, core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<test[9653f4f1d2ca1489]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:544:19
[INFO] [stdout]   41:     0x5fe39a4e7bdb - std[fce30c26ee6459b]::panic::catch_unwind::<core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<test[9653f4f1d2ca1489]::run_test_in_process::{closure#0}>, core[d17f6591d688f3bd]::result::Result<(), alloc[dad09f3a48280dba]::string::String>>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panic.rs:359:14
[INFO] [stdout]   42:     0x5fe39a4e7bdb - test[9653f4f1d2ca1489]::run_test_in_process
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:747:27
[INFO] [stdout]   43:     0x5fe39a4e7bdb - test[9653f4f1d2ca1489]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:668:43
[INFO] [stdout]   44:     0x5fe39a4e12f4 - test[9653f4f1d2ca1489]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:698:41
[INFO] [stdout]   45:     0x5fe39a4e12f4 - std[fce30c26ee6459b]::sys::backtrace::__rust_begin_short_backtrace::<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   46:     0x5fe39a4ea7e2 - std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked::<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   47:     0x5fe39a4ea7e2 - <core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[d17f6591d688f3bd]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   48:     0x5fe39a4ea7e2 - std[fce30c26ee6459b]::panicking::catch_unwind::do_call::<core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:581:40
[INFO] [stdout]   49:     0x5fe39a4ea7e2 - std[fce30c26ee6459b]::panicking::catch_unwind::<(), core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:544:19
[INFO] [stdout]   50:     0x5fe39a4ea7e2 - std[fce30c26ee6459b]::panic::catch_unwind::<core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panic.rs:359:14
[INFO] [stdout]   51:     0x5fe39a4ea7e2 - std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked::<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   52:     0x5fe39a4ea7e2 - <std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1} as core[d17f6591d688f3bd]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   53:     0x5fe39a7cab0f - <alloc[dad09f3a48280dba]::boxed::Box<dyn core[d17f6591d688f3bd]::ops::function::FnOnce<(), Output = ()> + core[d17f6591d688f3bd]::marker::Send> as core[d17f6591d688f3bd]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/alloc/src/boxed.rs:2271:9
[INFO] [stdout]   54:     0x5fe39a7cab0f - <std[fce30c26ee6459b]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   55:     0x74feac48baa4 - <unknown>
[INFO] [stdout]   56:     0x74feac518a64 - clone
[INFO] [stdout]   57:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- test::test_module::case_24_super stdout ----
[INFO] [stdout] [
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 4,
[INFO] [stdout]         superclass: None,
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 12,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 4,
[INFO] [stdout]     },
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 10,
[INFO] [stdout]         superclass: Some(
[INFO] [stdout]             Global(
[INFO] [stdout]                 4,
[INFO] [stdout]             ),
[INFO] [stdout]         ),
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 25,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 10,
[INFO] [stdout]     },
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 10,
[INFO] [stdout]     },
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Exit {
[INFO] [stdout]         code: 0,
[INFO] [stdout]     },
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 0,
[INFO] [stdout]         arg_count: 2,
[INFO] [stdout]     },
[INFO] [stdout]     String(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     GetLocal(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     String(
[INFO] [stdout]         2,
[INFO] [stdout]     ),
[INFO] [stdout]     GetLocal(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     String(
[INFO] [stdout]         3,
[INFO] [stdout]     ),
[INFO] [stdout]     Add,
[INFO] [stdout]     Add,
[INFO] [stdout]     Add,
[INFO] [stdout]     Add,
[INFO] [stdout]     Print,
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 0,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     String(
[INFO] [stdout]         5,
[INFO] [stdout]     ),
[INFO] [stdout]     Print,
[INFO] [stdout]     String(
[INFO] [stdout]         6,
[INFO] [stdout]     ),
[INFO] [stdout]     String(
[INFO] [stdout]         7,
[INFO] [stdout]     ),
[INFO] [stdout]     String(
[INFO] [stdout]         8,
[INFO] [stdout]     ),
[INFO] [stdout]     String(
[INFO] [stdout]         9,
[INFO] [stdout]     ),
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 4,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout] ]
[INFO] [stdout] Executing byte at 0: Class { name_index: 4, superclass: None } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: []
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 2: CreateGlobal { string_index: 4 } Stack: ["<class Base>"]
[INFO] [stdout] GC: checking value "<class Base>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Base>", "<fn foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 3: Class { name_index: 10, superclass: Some(Global(4)) } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Base>"
[INFO] [stdout] GC: reachable: ["<class Base>", "<fn foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Superclass address: <class Base>
[INFO] [stdout] Executing byte at 5: CreateGlobal { string_index: 10 } Stack: ["<class Derived>"]
[INFO] [stdout] GC: checking value "<class Derived>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Base>"
[INFO] [stdout] GC: reachable: ["<class Derived>", "<class Base>", "<fn foo>", "<fn foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 6: GetGlobal { string_index: 10 } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Derived>"
[INFO] [stdout] GC: checking value "<class Base>"
[INFO] [stdout] GC: reachable: ["<fn foo>", "<class Base>", "<class Derived>", "<fn foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 7: Call { arg_count: 0 } Stack: ["<class Derived>"]
[INFO] [stdout] GC: checking value "<class Derived>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Derived>"
[INFO] [stdout] GC: checking value "<class Base>"
[INFO] [stdout] GC: reachable: ["<class Derived>", "<fn foo>", "<class Base>", "<fn foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 8: DotProperty(0) Stack: ["<Derived instance>"]
[INFO] [stdout] GC: checking value "<Derived instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Derived>"
[INFO] [stdout] GC: checking value "<class Base>"
[INFO] [stdout] GC: reachable: ["<Derived instance>", "<fn foo>", "<class Derived>", "<fn foo>", "<fn foo>", "<class Base>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 9: Call { arg_count: 0 } Stack: ["<fn foo>"]
[INFO] [stdout] GC: checking value "<fn foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Derived>"
[INFO] [stdout] GC: checking value "<class Base>"
[INFO] [stdout] GC: reachable: ["<fn foo>", "<class Derived>", "<Derived instance>", "<fn foo>", "<fn foo>", "<class Base>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 26: String(5) Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Derived>"
[INFO] [stdout] GC: checking value "<class Base>"
[INFO] [stdout] GC: reachable: ["<fn foo>", "<fn foo>", "<class Derived>", "<class Base>"]
[INFO] [stdout] FREEING: ["<Derived instance>", "<fn foo>"]
[INFO] [stdout] Executing byte at 27: Print Stack: ["Derived.foo()"]
[INFO] [stdout] GC: checking value "Derived.foo()"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Derived>"
[INFO] [stdout] GC: checking value "<class Base>"
[INFO] [stdout] GC: reachable: ["<class Derived>", "Derived.foo()", "<fn foo>", "<class Base>", "<fn foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 28: String(6) Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Derived>"
[INFO] [stdout] GC: checking value "<class Base>"
[INFO] [stdout] GC: reachable: ["<fn foo>", "<class Derived>", "<class Base>", "<fn foo>"]
[INFO] [stdout] FREEING: ["Derived.foo()"]
[INFO] [stdout] Executing byte at 29: String(7) Stack: ["a"]
[INFO] [stdout] GC: checking value "a"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Derived>"
[INFO] [stdout] GC: checking value "<class Base>"
[INFO] [stdout] GC: reachable: ["a", "<fn foo>", "<class Derived>", "<fn foo>", "<class Base>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 30: String(8) Stack: ["a", "b"]
[INFO] [stdout] GC: checking value "a"
[INFO] [stdout] GC: checking value "b"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Derived>"
[INFO] [stdout] GC: checking value "<class Base>"
[INFO] [stdout] GC: reachable: ["a", "b", "<fn foo>", "<class Derived>", "<fn foo>", "<class Base>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 31: String(9) Stack: ["a", "b", "c"]
[INFO] [stdout] GC: checking value "a"
[INFO] [stdout] GC: checking value "b"
[INFO] [stdout] GC: checking value "c"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Derived>"
[INFO] [stdout] GC: checking value "<class Base>"
[INFO] [stdout] GC: reachable: ["a", "<class Derived>", "<fn foo>", "<class Base>", "b", "c", "<fn foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 32: GetCaptured(1) Stack: ["a", "b", "c", "d"]
[INFO] [stdout] GC: checking value "a"
[INFO] [stdout] GC: checking value "b"
[INFO] [stdout] GC: checking value "c"
[INFO] [stdout] GC: checking value "d"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Derived>"
[INFO] [stdout] GC: checking value "<class Base>"
[INFO] [stdout] GC: reachable: ["a", "b", "c", "<fn foo>", "<class Derived>", "<class Base>", "<fn foo>", "d"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 33: DotProperty(0) Stack: ["a", "b", "c", "d", "super"]
[INFO] [stdout] GC: checking value "a"
[INFO] [stdout] GC: checking value "b"
[INFO] [stdout] GC: checking value "c"
[INFO] [stdout] GC: checking value "d"
[INFO] [stdout] GC: checking value "super"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Derived>"
[INFO] [stdout] GC: checking value "<class Base>"
[INFO] [stdout] GC: reachable: ["d", "c", "<fn foo>", "a", "<class Derived>", "<class Base>", "<fn foo>", "b"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] 
[INFO] [stdout] thread 'test::test_module::case_24_super' (42) panicked at src/heap.rs:121:14:
[INFO] [stdout] Use after free: invalid address
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5fe39a7cb55a - std[fce30c26ee6459b]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x5fe39a7cb55a - std[fce30c26ee6459b]::backtrace_rs::backtrace::trace_unsynchronized::<std[fce30c26ee6459b]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x5fe39a7cb55a - std[fce30c26ee6459b]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x5fe39a7cb55a - <<std[fce30c26ee6459b]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[d17f6591d688f3bd]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x5fe39a7e272a - <core[d17f6591d688f3bd]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x5fe39a7e272a - core[d17f6591d688f3bd]::fmt::write
[INFO] [stdout]    6:     0x5fe39a7cffe2 - std[fce30c26ee6459b]::io::default_write_fmt::<alloc[dad09f3a48280dba]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/io/mod.rs:621:11
[INFO] [stdout]    7:     0x5fe39a7cffe2 - <alloc[dad09f3a48280dba]::vec::Vec<u8> as std[fce30c26ee6459b]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/io/mod.rs:1976:13
[INFO] [stdout]    8:     0x5fe39a7a9d6f - <std[fce30c26ee6459b]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x5fe39a7a9d6f - std[fce30c26ee6459b]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x5fe39a7c3409 - std[fce30c26ee6459b]::panicking::default_hook
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x5fe39a4e710c - <alloc[dad09f3a48280dba]::boxed::Box<dyn for<'a, 'b> core[d17f6591d688f3bd]::ops::function::Fn<(&'a std[fce30c26ee6459b]::panic::PanicHookInfo<'b>,), Output = ()> + core[d17f6591d688f3bd]::marker::Sync + core[d17f6591d688f3bd]::marker::Send> as core[d17f6591d688f3bd]::ops::function::Fn<(&std[fce30c26ee6459b]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/alloc/src/boxed.rs:2285:9
[INFO] [stdout]   12:     0x5fe39a4e710c - test[9653f4f1d2ca1489]::test_main_inner::<test[9653f4f1d2ca1489]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:155:21
[INFO] [stdout]   13:     0x5fe39a7c35c2 - <alloc[dad09f3a48280dba]::boxed::Box<dyn for<'a, 'b> core[d17f6591d688f3bd]::ops::function::Fn<(&'a std[fce30c26ee6459b]::panic::PanicHookInfo<'b>,), Output = ()> + core[d17f6591d688f3bd]::marker::Sync + core[d17f6591d688f3bd]::marker::Send> as core[d17f6591d688f3bd]::ops::function::Fn<(&std[fce30c26ee6459b]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/alloc/src/boxed.rs:2285:9
[INFO] [stdout]   14:     0x5fe39a7c35c2 - std[fce30c26ee6459b]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x5fe39a7a9e28 - std[fce30c26ee6459b]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:698:13
[INFO] [stdout]   16:     0x5fe39a79ee59 - std[fce30c26ee6459b]::sys::backtrace::__rust_end_short_backtrace::<std[fce30c26ee6459b]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x5fe39a7aab3d - __rustc[de152adb51f02556]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x5fe39a7e2f6c - core[d17f6591d688f3bd]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x5fe39a7e2c64 - core[d17f6591d688f3bd]::panicking::panic_display::<&str>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/panicking.rs:259:5
[INFO] [stdout]   20:     0x5fe39a7e2c64 - core[d17f6591d688f3bd]::option::expect_failed
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/option.rs:2260:5
[INFO] [stdout]   21:     0x5fe39a4ae5c0 - <core[d17f6591d688f3bd]::option::Option<&RustLox[3daf0c3e01451a46]::heap::HeapValue>>::expect
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/option.rs:971:21
[INFO] [stdout]   22:     0x5fe39a4cdb2e - <RustLox[3daf0c3e01451a46]::heap::Heap>::get
[INFO] [stdout]                                at /opt/rustwide/workdir/src/heap.rs:121:14
[INFO] [stdout]   23:     0x5fe39a49cbdd - <RustLox[3daf0c3e01451a46]::value::Value>::deref
[INFO] [stdout]                                at /opt/rustwide/workdir/src/value.rs:103:51
[INFO] [stdout]   24:     0x5fe39a49f36a - <RustLox[3daf0c3e01451a46]::interpreter::Interpreter<&mut alloc[dad09f3a48280dba]::string::String, &mut alloc[dad09f3a48280dba]::string::String>>::get_captured
[INFO] [stdout]                                at /opt/rustwide/workdir/src/interpreter.rs:188:42
[INFO] [stdout]   25:     0x5fe39a4a4053 - <RustLox[3daf0c3e01451a46]::interpreter::Interpreter<&mut alloc[dad09f3a48280dba]::string::String, &mut alloc[dad09f3a48280dba]::string::String>>::interpret_unsafe
[INFO] [stdout]                                at /opt/rustwide/workdir/src/interpreter.rs:800:49
[INFO] [stdout]   26:     0x5fe39a4abdda - <RustLox[3daf0c3e01451a46]::interpreter::Interpreter<&mut alloc[dad09f3a48280dba]::string::String, &mut alloc[dad09f3a48280dba]::string::String>>::interpret
[INFO] [stdout]                                at /opt/rustwide/workdir/src/interpreter.rs:866:20
[INFO] [stdout]   27:     0x5fe39a4c3a36 - RustLox[3daf0c3e01451a46]::test::test_interpret_file::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:100:29
[INFO] [stdout]   28:     0x5fe39a4c863a - <RustLox[3daf0c3e01451a46]::test::test_interpret_file::{closure#0} as core[d17f6591d688f3bd]::ops::function::FnOnce<(&str,)>>::call_once
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   29:     0x5fe39a4c6321 - RustLox[3daf0c3e01451a46]::test::test_file
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:38:18
[INFO] [stdout]   30:     0x5fe39a4c60aa - RustLox[3daf0c3e01451a46]::test::test_interpret_file
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:92:5
[INFO] [stdout]   31:     0x5fe39a4c4fe6 - RustLox[3daf0c3e01451a46]::test::test_module
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:160:9
[INFO] [stdout]   32:     0x5fe39a4cdfd2 - RustLox[3daf0c3e01451a46]::test::test_module::case_24_super
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:125:1
[INFO] [stdout]   33:     0x5fe39a4ca6f7 - RustLox[3daf0c3e01451a46]::test::test_module::case_24_super::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:125:10
[INFO] [stdout]   34:     0x5fe39a4c8936 - <RustLox[3daf0c3e01451a46]::test::test_module::case_24_super::{closure#0} as core[d17f6591d688f3bd]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   35:     0x5fe39a4da3eb - <fn() -> core[d17f6591d688f3bd]::result::Result<(), alloc[dad09f3a48280dba]::string::String> as core[d17f6591d688f3bd]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   36:     0x5fe39a4da3eb - test[9653f4f1d2ca1489]::__rust_begin_short_backtrace::<core[d17f6591d688f3bd]::result::Result<(), alloc[dad09f3a48280dba]::string::String>, fn() -> core[d17f6591d688f3bd]::result::Result<(), alloc[dad09f3a48280dba]::string::String>>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:724:18
[INFO] [stdout]   37:     0x5fe39a4e7bdb - test[9653f4f1d2ca1489]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:747:74
[INFO] [stdout]   38:     0x5fe39a4e7bdb - <core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<test[9653f4f1d2ca1489]::run_test_in_process::{closure#0}> as core[d17f6591d688f3bd]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   39:     0x5fe39a4e7bdb - std[fce30c26ee6459b]::panicking::catch_unwind::do_call::<core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<test[9653f4f1d2ca1489]::run_test_in_process::{closure#0}>, core[d17f6591d688f3bd]::result::Result<(), alloc[dad09f3a48280dba]::string::String>>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:581:40
[INFO] [stdout]   40:     0x5fe39a4e7bdb - std[fce30c26ee6459b]::panicking::catch_unwind::<core[d17f6591d688f3bd]::result::Result<(), alloc[dad09f3a48280dba]::string::String>, core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<test[9653f4f1d2ca1489]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:544:19
[INFO] [stdout]   41:     0x5fe39a4e7bdb - std[fce30c26ee6459b]::panic::catch_unwind::<core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<test[9653f4f1d2ca1489]::run_test_in_process::{closure#0}>, core[d17f6591d688f3bd]::result::Result<(), alloc[dad09f3a48280dba]::string::String>>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panic.rs:359:14
[INFO] [stdout]   42:     0x5fe39a4e7bdb - test[9653f4f1d2ca1489]::run_test_in_process
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:747:27
[INFO] [stdout]   43:     0x5fe39a4e7bdb - test[9653f4f1d2ca1489]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:668:43
[INFO] [stdout]   44:     0x5fe39a4e12f4 - test[9653f4f1d2ca1489]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:698:41
[INFO] [stdout]   45:     0x5fe39a4e12f4 - std[fce30c26ee6459b]::sys::backtrace::__rust_begin_short_backtrace::<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   46:     0x5fe39a4ea7e2 - std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked::<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   47:     0x5fe39a4ea7e2 - <core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[d17f6591d688f3bd]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   48:     0x5fe39a4ea7e2 - std[fce30c26ee6459b]::panicking::catch_unwind::do_call::<core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:581:40
[INFO] [stdout]   49:     0x5fe39a4ea7e2 - std[fce30c26ee6459b]::panicking::catch_unwind::<(), core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:544:19
[INFO] [stdout]   50:     0x5fe39a4ea7e2 - std[fce30c26ee6459b]::panic::catch_unwind::<core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panic.rs:359:14
[INFO] [stdout]   51:     0x5fe39a4ea7e2 - std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked::<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   52:     0x5fe39a4ea7e2 - <std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1} as core[d17f6591d688f3bd]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   53:     0x5fe39a7cab0f - <alloc[dad09f3a48280dba]::boxed::Box<dyn core[d17f6591d688f3bd]::ops::function::FnOnce<(), Output = ()> + core[d17f6591d688f3bd]::marker::Send> as core[d17f6591d688f3bd]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/alloc/src/boxed.rs:2271:9
[INFO] [stdout]   54:     0x5fe39a7cab0f - <std[fce30c26ee6459b]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   55:     0x74feac48baa4 - <unknown>
[INFO] [stdout]   56:     0x74feac518a64 - clone
[INFO] [stdout]   57:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- test::test_module::case_14_inheritance stdout ----
[INFO] [stdout] [
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 4,
[INFO] [stdout]         superclass: None,
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 36,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 45,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 4,
[INFO] [stdout]     },
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 7,
[INFO] [stdout]         superclass: Some(
[INFO] [stdout]             Global(
[INFO] [stdout]                 4,
[INFO] [stdout]             ),
[INFO] [stdout]         ),
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 56,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 67,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 7,
[INFO] [stdout]     },
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 7,
[INFO] [stdout]     },
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 5,
[INFO] [stdout]     },
[INFO] [stdout]     String(
[INFO] [stdout]         8,
[INFO] [stdout]     ),
[INFO] [stdout]     String(
[INFO] [stdout]         9,
[INFO] [stdout]     ),
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 5,
[INFO] [stdout]     },
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         3,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 2,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 5,
[INFO] [stdout]     },
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     String(
[INFO] [stdout]         10,
[INFO] [stdout]     ),
[INFO] [stdout]     String(
[INFO] [stdout]         11,
[INFO] [stdout]     ),
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 5,
[INFO] [stdout]     },
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         5,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 2,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 5,
[INFO] [stdout]     },
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         6,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 5,
[INFO] [stdout]     },
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Exit {
[INFO] [stdout]         code: 0,
[INFO] [stdout]     },
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 0,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Print,
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         2,
[INFO] [stdout]     ),
[INFO] [stdout]     Print,
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 3,
[INFO] [stdout]         arg_count: 2,
[INFO] [stdout]     },
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     GetLocal(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     AssignField {
[INFO] [stdout]         field_name_index: 1,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     GetLocal(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     AssignField {
[INFO] [stdout]         field_name_index: 2,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 5,
[INFO] [stdout]         arg_count: 2,
[INFO] [stdout]     },
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     GetLocal(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     AssignField {
[INFO] [stdout]         field_name_index: 1,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     GetLocal(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     AssignField {
[INFO] [stdout]         field_name_index: 2,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 6,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Print,
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         2,
[INFO] [stdout]     ),
[INFO] [stdout]     Print,
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout] ]
[INFO] [stdout] Executing byte at 0: Class { name_index: 4, superclass: None } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: []
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 3: CreateGlobal { string_index: 4 } Stack: ["<class Foo>"]
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn foo>", "<fn fooPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 4: Class { name_index: 7, superclass: Some(Global(4)) } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn foo>", "<fn fooPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Superclass address: <class Foo>
[INFO] [stdout] Superclass address: <class Foo>
[INFO] [stdout] Executing byte at 7: CreateGlobal { string_index: 7 } Stack: ["<class Bar>"]
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<class Bar>", "<class Foo>", "<fn fooPrint>", "<fn bar>", "<fn foo>", "<fn barPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 8: GetGlobal { string_index: 7 } Stack: []
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn foo>", "<fn bar>", "<fn fooPrint>", "<class Bar>", "<fn barPrint>", "<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 9: Call { arg_count: 0 } Stack: ["<class Bar>"]
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn foo>", "<class Foo>", "<fn fooPrint>", "<fn barPrint>", "<class Bar>", "<fn bar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 10: CreateGlobal { string_index: 5 } Stack: ["<Bar instance>"]
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn barPrint>", "<class Bar>", "<fn bar>", "<fn fooPrint>", "<fn barPrint>", "<fn bar>", "<fn foo>", "<class Foo>", "<Bar instance>", "<fn foo>", "<fn fooPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 11: String(8) Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn fooPrint>", "<Bar instance>", "<fn bar>", "<fn foo>", "<fn barPrint>", "<fn foo>", "<fn fooPrint>", "<class Foo>", "<fn bar>", "<class Bar>", "<fn barPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 12: String(9) Stack: ["foo 1"]
[INFO] [stdout] GC: checking value "foo 1"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn fooPrint>", "foo 1", "<class Bar>", "<Bar instance>", "<fn barPrint>", "<fn bar>", "<class Foo>", "<fn foo>", "<fn foo>", "<fn barPrint>", "<fn bar>", "<fn fooPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 13: GetGlobal { string_index: 5 } Stack: ["foo 1", "foo 2"]
[INFO] [stdout] GC: checking value "foo 1"
[INFO] [stdout] GC: checking value "foo 2"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn fooPrint>", "<class Foo>", "foo 2", "<fn bar>", "<fn barPrint>", "<fn foo>", "<fn bar>", "foo 1", "<fn fooPrint>", "<fn foo>", "<class Bar>", "<Bar instance>", "<fn barPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 14: DotProperty(3) Stack: ["foo 1", "foo 2", "<Bar instance>"]
[INFO] [stdout] GC: checking value "foo 1"
[INFO] [stdout] GC: checking value "foo 2"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["foo 2", "<fn foo>", "<fn foo>", "<fn barPrint>", "<Bar instance>", "<fn bar>", "<fn fooPrint>", "<class Bar>", "<fn bar>", "<fn barPrint>", "<class Foo>", "foo 1", "<fn fooPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 15: Call { arg_count: 2 } Stack: ["foo 1", "foo 2", "<fn foo>"]
[INFO] [stdout] GC: checking value "foo 1"
[INFO] [stdout] GC: checking value "foo 2"
[INFO] [stdout] GC: checking value "<fn foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn foo>", "<class Bar>", "<class Foo>", "foo 1", "foo 2", "<fn fooPrint>", "<fn barPrint>", "<Bar instance>", "<fn bar>", "<fn foo>", "<fn bar>", "<fn barPrint>", "<fn fooPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 46: GetCaptured(0) Stack: ["foo 1", "foo 2"]
[INFO] [stdout] GC: checking value "foo 1"
[INFO] [stdout] GC: checking value "foo 2"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn foo>", "<fn bar>", "<fn barPrint>", "<fn fooPrint>", "foo 1", "foo 2", "<class Foo>", "<fn bar>", "<fn foo>", "<class Bar>", "<Bar instance>", "<fn fooPrint>", "<fn barPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 47: GetLocal(0) Stack: ["foo 1", "foo 2", "<Bar instance>"]
[INFO] [stdout] GC: checking value "foo 1"
[INFO] [stdout] GC: checking value "foo 2"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn barPrint>", "<class Foo>", "<Bar instance>", "<fn fooPrint>", "<class Bar>", "<fn bar>", "<fn bar>", "foo 2", "<fn foo>", "<fn barPrint>", "foo 1", "<fn fooPrint>", "<fn foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 48: AssignField { field_name_index: 1 } Stack: ["foo 1", "foo 2", "<Bar instance>", "foo 1"]
[INFO] [stdout] GC: checking value "foo 1"
[INFO] [stdout] GC: checking value "foo 2"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "foo 1"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["foo 2", "<fn foo>", "<Bar instance>", "<class Foo>", "foo 1", "<fn barPrint>", "<fn foo>", "<fn fooPrint>", "<class Bar>", "<fn bar>", "<fn bar>", "<fn barPrint>", "<fn fooPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 49: Pop(1) Stack: ["foo 1", "foo 2", "foo 1"]
[INFO] [stdout] GC: checking value "foo 1"
[INFO] [stdout] GC: checking value "foo 2"
[INFO] [stdout] GC: checking value "foo 1"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn foo>", "foo 2", "<fn fooPrint>", "<class Bar>", "<fn bar>", "<Bar instance>", "<fn barPrint>", "<class Foo>", "foo 1", "<fn barPrint>", "<fn bar>", "<fn foo>", "<fn fooPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 50: GetCaptured(0) Stack: ["foo 1", "foo 2"]
[INFO] [stdout] GC: checking value "foo 1"
[INFO] [stdout] GC: checking value "foo 2"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn foo>", "<class Foo>", "<Bar instance>", "<fn barPrint>", "<fn fooPrint>", "<class Bar>", "<fn bar>", "foo 2", "<fn foo>", "foo 1", "<fn bar>", "<fn fooPrint>", "<fn barPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 51: GetLocal(1) Stack: ["foo 1", "foo 2", "<Bar instance>"]
[INFO] [stdout] GC: checking value "foo 1"
[INFO] [stdout] GC: checking value "foo 2"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn foo>", "foo 2", "<Bar instance>", "<class Bar>", "<fn bar>", "<fn bar>", "foo 1", "<fn fooPrint>", "<fn barPrint>", "<fn foo>", "<fn barPrint>", "<class Foo>", "<fn fooPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 52: AssignField { field_name_index: 2 } Stack: ["foo 1", "foo 2", "<Bar instance>", "foo 2"]
[INFO] [stdout] GC: checking value "foo 1"
[INFO] [stdout] GC: checking value "foo 2"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "foo 2"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<class Bar>", "<fn foo>", "<class Foo>", "foo 1", "<Bar instance>", "foo 2", "<fn bar>", "<fn fooPrint>", "<fn fooPrint>", "<fn barPrint>", "<fn foo>", "<fn bar>", "<fn barPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 53: Pop(1) Stack: ["foo 1", "foo 2", "foo 2"]
[INFO] [stdout] GC: checking value "foo 1"
[INFO] [stdout] GC: checking value "foo 2"
[INFO] [stdout] GC: checking value "foo 2"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["foo 1", "<class Foo>", "<fn bar>", "<fn barPrint>", "foo 2", "<Bar instance>", "<fn fooPrint>", "<class Bar>", "<fn fooPrint>", "<fn foo>", "<fn foo>", "<fn bar>", "<fn barPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 54: Nil Stack: ["foo 1", "foo 2"]
[INFO] [stdout] GC: checking value "foo 1"
[INFO] [stdout] GC: checking value "foo 2"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<Bar instance>", "<fn barPrint>", "<fn bar>", "foo 2", "<fn fooPrint>", "<fn bar>", "<fn foo>", "<class Foo>", "<class Bar>", "<fn barPrint>", "<fn foo>", "<fn fooPrint>", "foo 1"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 55: Return Stack: ["foo 1", "foo 2", "nil"]
[INFO] [stdout] GC: checking value "foo 1"
[INFO] [stdout] GC: checking value "foo 2"
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["foo 1", "<fn bar>", "<class Foo>", "<fn fooPrint>", "<fn fooPrint>", "foo 2", "<Bar instance>", "<fn barPrint>", "<class Bar>", "<fn bar>", "<fn barPrint>", "<fn foo>", "<fn foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 16: Pop(1) Stack: ["nil"]
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn bar>", "foo 1", "<fn fooPrint>", "<fn bar>", "<fn fooPrint>", "<fn foo>", "<fn barPrint>", "<fn foo>", "foo 2", "<fn barPrint>", "<class Foo>", "<class Bar>", "<Bar instance>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 17: GetGlobal { string_index: 5 } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<Bar instance>", "<class Foo>", "<fn foo>", "foo 1", "<fn bar>", "<class Bar>", "<fn fooPrint>", "<fn barPrint>", "<fn bar>", "<fn barPrint>", "<fn fooPrint>", "<fn foo>", "foo 2"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 18: DotProperty(0) Stack: ["<Bar instance>"]
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn barPrint>", "foo 2", "<Bar instance>", "<fn foo>", "foo 1", "<class Bar>", "<fn fooPrint>", "<fn barPrint>", "<fn foo>", "<fn bar>", "<class Foo>", "<fn fooPrint>", "<fn bar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 19: Call { arg_count: 0 } Stack: ["<fn fooPrint>"]
[INFO] [stdout] GC: checking value "<fn fooPrint>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["foo 2", "<fn foo>", "<class Bar>", "<class Foo>", "<fn foo>", "<Bar instance>", "<fn bar>", "<fn barPrint>", "<fn bar>", "foo 1", "<fn barPrint>", "<fn fooPrint>", "<fn fooPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 37: GetCaptured(0) Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["foo 1", "<class Bar>", "<class Foo>", "<fn barPrint>", "foo 2", "<Bar instance>", "<fn bar>", "<fn bar>", "<fn barPrint>", "<fn fooPrint>", "<fn fooPrint>", "<fn foo>", "<fn foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 38: DotProperty(1) Stack: ["<Bar instance>"]
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn bar>", "<fn fooPrint>", "<fn foo>", "<fn barPrint>", "<Bar instance>", "<class Bar>", "<fn bar>", "<fn foo>", "<fn barPrint>", "foo 1", "<class Foo>", "foo 2", "<fn fooPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 39: Print Stack: ["foo 1"]
[INFO] [stdout] GC: checking value "foo 1"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn foo>", "foo 2", "<class Bar>", "<fn bar>", "<class Foo>", "<fn foo>", "<fn barPrint>", "<fn bar>", "<Bar instance>", "<fn fooPrint>", "<fn barPrint>", "foo 1", "<fn fooPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 40: GetCaptured(0) Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn barPrint>", "<class Foo>", "foo 2", "<Bar instance>", "<fn foo>", "<fn fooPrint>", "<fn bar>", "<fn barPrint>", "<fn foo>", "<class Bar>", "<fn bar>", "foo 1", "<fn fooPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 41: DotProperty(2) Stack: ["<Bar instance>"]
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["foo 1", "<class Foo>", "<Bar instance>", "<fn fooPrint>", "<fn fooPrint>", "foo 2", "<fn bar>", "<fn foo>", "<fn foo>", "<fn barPrint>", "<class Bar>", "<fn bar>", "<fn barPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 42: Print Stack: ["foo 2"]
[INFO] [stdout] GC: checking value "foo 2"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn foo>", "<class Foo>", "<fn foo>", "<Bar instance>", "foo 2", "foo 1", "<class Bar>", "<fn fooPrint>", "<fn bar>", "<fn bar>", "<fn barPrint>", "<fn barPrint>", "<fn fooPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 43: Nil Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn barPrint>", "<fn fooPrint>", "<fn foo>", "<class Foo>", "foo 2", "<fn foo>", "<Bar instance>", "foo 1", "<class Bar>", "<fn fooPrint>", "<fn bar>", "<fn barPrint>", "<fn bar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 44: Return Stack: ["nil"]
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn fooPrint>", "foo 1", "<fn bar>", "<class Bar>", "<class Foo>", "<Bar instance>", "<fn foo>", "foo 2", "<fn fooPrint>", "<fn bar>", "<fn foo>", "<fn barPrint>", "<fn barPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 20: Pop(1) Stack: ["nil"]
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<class Bar>", "<Bar instance>", "foo 2", "<fn foo>", "<fn bar>", "<fn bar>", "<fn foo>", "<fn barPrint>", "<fn barPrint>", "<class Foo>", "<fn fooPrint>", "foo 1", "<fn fooPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 21: String(10) Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn fooPrint>", "<fn fooPrint>", "<Bar instance>", "<fn bar>", "foo 2", "<fn foo>", "<class Foo>", "<fn barPrint>", "foo 1", "<fn bar>", "<fn barPrint>", "<fn foo>", "<class Bar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 22: String(11) Stack: ["bar 1"]
[INFO] [stdout] GC: checking value "bar 1"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn foo>", "<fn fooPrint>", "<fn bar>", "<Bar instance>", "<class Foo>", "<fn fooPrint>", "<fn foo>", "<fn barPrint>", "foo 1", "bar 1", "<class Bar>", "<fn bar>", "foo 2", "<fn barPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 23: GetGlobal { string_index: 5 } Stack: ["bar 1", "bar 2"]
[INFO] [stdout] GC: checking value "bar 1"
[INFO] [stdout] GC: checking value "bar 2"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn bar>", "<fn foo>", "bar 2", "<fn fooPrint>", "<fn foo>", "foo 2", "foo 1", "<Bar instance>", "<class Bar>", "<fn barPrint>", "bar 1", "<fn fooPrint>", "<fn barPrint>", "<fn bar>", "<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 24: DotProperty(5) Stack: ["bar 1", "bar 2", "<Bar instance>"]
[INFO] [stdout] GC: checking value "bar 1"
[INFO] [stdout] GC: checking value "bar 2"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn bar>", "<fn barPrint>", "<fn barPrint>", "<fn fooPrint>", "bar 1", "<fn bar>", "<class Bar>", "<fn foo>", "<fn fooPrint>", "<class Foo>", "<Bar instance>", "foo 2", "<fn foo>", "foo 1", "bar 2"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 25: Call { arg_count: 2 } Stack: ["bar 1", "bar 2", "<fn bar>"]
[INFO] [stdout] GC: checking value "bar 1"
[INFO] [stdout] GC: checking value "bar 2"
[INFO] [stdout] GC: checking value "<fn bar>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<Bar instance>", "<fn bar>", "<fn bar>", "<fn barPrint>", "<class Bar>", "bar 1", "<class Foo>", "foo 1", "<fn foo>", "<fn foo>", "bar 2", "foo 2", "<fn barPrint>", "<fn fooPrint>", "<fn fooPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 57: GetCaptured(0) Stack: ["bar 1", "bar 2"]
[INFO] [stdout] GC: checking value "bar 1"
[INFO] [stdout] GC: checking value "bar 2"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn bar>", "<fn foo>", "bar 2", "<class Foo>", "<class Bar>", "<fn bar>", "bar 1", "<fn foo>", "foo 1", "<fn fooPrint>", "foo 2", "<fn barPrint>", "<fn fooPrint>", "<Bar instance>", "<fn barPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 58: GetLocal(0) Stack: ["bar 1", "bar 2", "<Bar instance>"]
[INFO] [stdout] GC: checking value "bar 1"
[INFO] [stdout] GC: checking value "bar 2"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn barPrint>", "bar 2", "<fn bar>", "<fn foo>", "<class Bar>", "<fn foo>", "<fn bar>", "<class Foo>", "foo 2", "<fn fooPrint>", "<fn fooPrint>", "<fn barPrint>", "<Bar instance>", "bar 1", "foo 1"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 59: AssignField { field_name_index: 1 } Stack: ["bar 1", "bar 2", "<Bar instance>", "bar 1"]
[INFO] [stdout] GC: checking value "bar 1"
[INFO] [stdout] GC: checking value "bar 2"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "bar 1"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn bar>", "<class Foo>", "<fn foo>", "bar 1", "foo 2", "bar 2", "<class Bar>", "<fn fooPrint>", "<fn barPrint>", "<Bar instance>", "foo 1", "<fn foo>", "<fn barPrint>", "<fn fooPrint>", "<fn bar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 60: Pop(1) Stack: ["bar 1", "bar 2", "bar 1"]
[INFO] [stdout] GC: checking value "bar 1"
[INFO] [stdout] GC: checking value "bar 2"
[INFO] [stdout] GC: checking value "bar 1"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<Bar instance>", "bar 2", "<class Foo>", "<fn barPrint>", "<class Bar>", "<fn foo>", "<fn fooPrint>", "bar 1", "<fn bar>", "<fn bar>", "foo 2", "<fn foo>", "<fn barPrint>", "<fn fooPrint>"]
[INFO] [stdout] FREEING: ["foo 1"]
[INFO] [stdout] Executing byte at 61: GetCaptured(0) Stack: ["bar 1", "bar 2"]
[INFO] [stdout] GC: checking value "bar 1"
[INFO] [stdout] GC: checking value "bar 2"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn bar>", "<fn barPrint>", "<Bar instance>", "<fn bar>", "<class Foo>", "<fn fooPrint>", "<class Bar>", "foo 2", "bar 1", "bar 2", "<fn fooPrint>", "<fn barPrint>", "<fn foo>", "<fn foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 62: GetLocal(1) Stack: ["bar 1", "bar 2", "<Bar instance>"]
[INFO] [stdout] GC: checking value "bar 1"
[INFO] [stdout] GC: checking value "bar 2"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["bar 2", "<class Bar>", "<class Foo>", "<Bar instance>", "<fn fooPrint>", "<fn fooPrint>", "<fn barPrint>", "<fn bar>", "<fn bar>", "bar 1", "<fn foo>", "foo 2", "<fn barPrint>", "<fn foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 63: AssignField { field_name_index: 2 } Stack: ["bar 1", "bar 2", "<Bar instance>", "bar 2"]
[INFO] [stdout] GC: checking value "bar 1"
[INFO] [stdout] GC: checking value "bar 2"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "bar 2"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn barPrint>", "<fn fooPrint>", "<fn foo>", "<class Bar>", "<Bar instance>", "<fn fooPrint>", "<fn bar>", "foo 2", "<fn bar>", "<class Foo>", "<fn foo>", "bar 2", "bar 1", "<fn barPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 64: Pop(1) Stack: ["bar 1", "bar 2", "bar 2"]
[INFO] [stdout] GC: checking value "bar 1"
[INFO] [stdout] GC: checking value "bar 2"
[INFO] [stdout] GC: checking value "bar 2"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn bar>", "<fn foo>", "<fn fooPrint>", "bar 1", "<fn foo>", "<fn barPrint>", "<fn fooPrint>", "bar 2", "<fn bar>", "<class Foo>", "<fn barPrint>", "<class Bar>", "<Bar instance>"]
[INFO] [stdout] FREEING: ["foo 2"]
[INFO] [stdout] Executing byte at 65: Nil Stack: ["bar 1", "bar 2"]
[INFO] [stdout] GC: checking value "bar 1"
[INFO] [stdout] GC: checking value "bar 2"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn barPrint>", "<fn foo>", "<Bar instance>", "<fn bar>", "bar 1", "bar 2", "<class Bar>", "<fn foo>", "<fn bar>", "<fn fooPrint>", "<class Foo>", "<fn fooPrint>", "<fn barPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 66: Return Stack: ["bar 1", "bar 2", "nil"]
[INFO] [stdout] GC: checking value "bar 1"
[INFO] [stdout] GC: checking value "bar 2"
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn fooPrint>", "<fn fooPrint>", "bar 1", "<fn bar>", "<fn bar>", "<fn barPrint>", "<fn foo>", "<class Bar>", "<class Foo>", "<fn barPrint>", "<fn foo>", "bar 2", "<Bar instance>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 26: Pop(1) Stack: ["nil"]
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn bar>", "<Bar instance>", "<fn foo>", "<fn foo>", "<fn fooPrint>", "bar 1", "<class Bar>", "<fn bar>", "bar 2", "<fn barPrint>", "<fn fooPrint>", "<fn barPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 27: GetGlobal { string_index: 5 } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn bar>", "<fn bar>", "<fn barPrint>", "<Bar instance>", "<fn foo>", "<fn fooPrint>", "bar 2", "<class Foo>", "bar 1", "<class Bar>", "<fn foo>", "<fn barPrint>", "<fn fooPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 28: DotProperty(6) Stack: ["<Bar instance>"]
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn foo>", "bar 1", "<fn barPrint>", "<fn barPrint>", "<class Foo>", "<fn fooPrint>", "bar 2", "<fn bar>", "<class Bar>", "<fn bar>", "<fn fooPrint>", "<fn foo>", "<Bar instance>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 29: Call { arg_count: 0 } Stack: ["<fn barPrint>"]
[INFO] [stdout] GC: checking value "<fn barPrint>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["bar 1", "<Bar instance>", "<fn bar>", "<class Foo>", "<fn barPrint>", "<fn barPrint>", "<fn foo>", "<fn fooPrint>", "bar 2", "<fn fooPrint>", "<class Bar>", "<fn bar>", "<fn foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 68: GetCaptured(0) Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn foo>", "<class Foo>", "<Bar instance>", "<fn fooPrint>", "<fn barPrint>", "<fn foo>", "bar 2", "bar 1", "<class Bar>", "<fn bar>", "<fn fooPrint>", "<fn barPrint>", "<fn bar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 69: DotProperty(1) Stack: ["<Bar instance>"]
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn bar>", "<fn foo>", "<fn barPrint>", "<class Bar>", "<fn barPrint>", "<fn bar>", "<fn foo>", "<Bar instance>", "bar 2", "bar 1", "<fn fooPrint>", "<fn fooPrint>", "<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 70: Print Stack: ["bar 1"]
[INFO] [stdout] GC: checking value "bar 1"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn barPrint>", "<class Bar>", "<fn fooPrint>", "<fn fooPrint>", "<fn barPrint>", "<fn foo>", "<fn bar>", "<fn foo>", "bar 1", "<fn bar>", "<Bar instance>", "bar 2"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 71: GetCaptured(0) Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn fooPrint>", "<fn foo>", "<fn fooPrint>", "<Bar instance>", "bar 2", "<fn bar>", "bar 1", "<class Bar>", "<fn bar>", "<fn barPrint>", "<fn barPrint>", "<fn foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 72: DotProperty(2) Stack: ["<Bar instance>"]
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<class Bar>", "<fn barPrint>", "<fn fooPrint>", "<fn barPrint>", "<fn foo>", "<Bar instance>", "<fn fooPrint>", "bar 1", "<fn bar>", "<fn foo>", "<class Foo>", "<fn bar>", "bar 2"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 73: Print Stack: ["bar 2"]
[INFO] [stdout] GC: checking value "bar 2"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn barPrint>", "<fn bar>", "<fn barPrint>", "<fn foo>", "bar 2", "bar 1", "<class Foo>", "<class Bar>", "<fn fooPrint>", "<fn bar>", "<fn fooPrint>", "<Bar instance>", "<fn foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 74: Nil Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn foo>", "<fn bar>", "<fn fooPrint>", "<fn bar>", "<class Foo>", "<fn fooPrint>", "<fn foo>", "<fn barPrint>", "bar 1", "bar 2", "<Bar instance>", "<class Bar>", "<fn barPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 75: Return Stack: ["nil"]
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn fooPrint>", "<class Bar>", "<Bar instance>", "<fn bar>", "<fn barPrint>", "<fn foo>", "bar 2", "<fn bar>", "<fn barPrint>", "<class Foo>", "<fn foo>", "bar 1", "<fn fooPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 30: Pop(1) Stack: ["nil"]
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn bar>", "<fn barPrint>", "<class Foo>", "<fn fooPrint>", "<class Bar>", "<fn foo>", "<fn fooPrint>", "bar 2", "<Bar instance>", "<fn foo>", "bar 1", "<fn barPrint>", "<fn bar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 31: GetGlobal { string_index: 5 } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<class Bar>", "<fn bar>", "<fn fooPrint>", "<fn fooPrint>", "<class Foo>", "<fn foo>", "<fn foo>", "<fn barPrint>", "bar 2", "<fn bar>", "<Bar instance>", "bar 1", "<fn barPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 32: DotProperty(0) Stack: ["<Bar instance>"]
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn barPrint>", "bar 1", "<fn bar>", "<fn bar>", "<class Bar>", "<Bar instance>", "<class Foo>", "<fn fooPrint>", "<fn fooPrint>", "<fn barPrint>", "<fn foo>", "bar 2", "<fn foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 33: Call { arg_count: 0 } Stack: ["<fn fooPrint>"]
[INFO] [stdout] GC: checking value "<fn fooPrint>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn bar>", "<fn barPrint>", "<fn foo>", "<Bar instance>", "<fn fooPrint>", "bar 1", "<fn barPrint>", "<fn foo>", "<class Bar>", "<fn fooPrint>", "<fn bar>", "bar 2", "<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 37: GetCaptured(0) Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn foo>", "<fn barPrint>", "<fn foo>", "<fn bar>", "<fn barPrint>", "<Bar instance>", "<fn fooPrint>", "bar 2", "<class Bar>", "bar 1", "<fn bar>", "<fn fooPrint>", "<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 38: DotProperty(1) Stack: ["<Bar instance>"]
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<Bar instance>", "<fn barPrint>", "<fn bar>", "<fn foo>", "<fn bar>", "<fn barPrint>", "<fn fooPrint>", "bar 2", "bar 1", "<fn foo>", "<class Foo>", "<class Bar>", "<fn fooPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 39: Print Stack: ["bar 1"]
[INFO] [stdout] GC: checking value "bar 1"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<Bar instance>", "<fn fooPrint>", "<fn barPrint>", "<class Foo>", "bar 1", "<fn bar>", "<fn foo>", "<fn barPrint>", "bar 2", "<fn foo>", "<class Bar>", "<fn fooPrint>", "<fn bar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 40: GetCaptured(0) Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn fooPrint>", "<fn barPrint>", "<fn bar>", "<fn barPrint>", "<fn bar>", "<fn foo>", "bar 2", "<fn fooPrint>", "<class Bar>", "<Bar instance>", "bar 1", "<fn foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 41: DotProperty(2) Stack: ["<Bar instance>"]
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "bar 2", "<fn bar>", "<fn foo>", "<fn fooPrint>", "bar 1", "<fn bar>", "<fn barPrint>", "<Bar instance>", "<fn foo>", "<fn barPrint>", "<class Bar>", "<fn fooPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 42: Print Stack: ["bar 2"]
[INFO] [stdout] GC: checking value "bar 2"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["bar 2", "<class Bar>", "<Bar instance>", "<fn fooPrint>", "bar 1", "<fn fooPrint>", "<fn bar>", "<fn barPrint>", "<fn foo>", "<fn bar>", "<fn barPrint>", "<class Foo>", "<fn foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 43: Nil Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<Bar instance>", "<fn foo>", "<fn fooPrint>", "<fn fooPrint>", "<fn barPrint>", "bar 1", "<class Foo>", "bar 2", "<fn bar>", "<fn barPrint>", "<fn bar>", "<class Bar>", "<fn foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 44: Return Stack: ["nil"]
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<Bar instance>", "<fn fooPrint>", "<fn bar>", "bar 1", "<fn fooPrint>", "<fn barPrint>", "<fn barPrint>", "<class Foo>", "<fn foo>", "bar 2", "<class Bar>", "<fn bar>", "<fn foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 34: Pop(1) Stack: ["nil"]
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn bar>", "<fn barPrint>", "<fn fooPrint>", "bar 1", "<fn foo>", "<class Foo>", "<fn foo>", "bar 2", "<fn fooPrint>", "<class Bar>", "<fn bar>", "<Bar instance>", "<fn barPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 35: Exit { code: 0 } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn bar>", "<fn foo>", "<class Foo>", "<Bar instance>", "<fn foo>", "<fn bar>", "<fn barPrint>", "<class Bar>", "<fn fooPrint>", "bar 2", "<fn barPrint>", "bar 1", "<fn fooPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] [
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 5,
[INFO] [stdout]         type_: Function,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 1,
[INFO] [stdout]         superclass: Some(
[INFO] [stdout]             Global(
[INFO] [stdout]                 0,
[INFO] [stdout]             ),
[INFO] [stdout]         ),
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 1,
[INFO] [stdout]     },
[INFO] [stdout]     Exit {
[INFO] [stdout]         code: 0,
[INFO] [stdout]     },
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 0,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout] ]
[INFO] [stdout] Executing byte at 0: Callable { address: 5, type_: Function } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: []
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 1: CreateGlobal { string_index: 0 } Stack: ["<fn foo>"]
[INFO] [stdout] GC: checking value "<fn foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 2: Class { name_index: 1, superclass: Some(Global(0)) } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<fn foo>"
[INFO] [stdout] GC: reachable: ["<fn foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] [
[INFO] [stdout]     Nil,
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 1,
[INFO] [stdout]         superclass: Some(
[INFO] [stdout]             Global(
[INFO] [stdout]                 0,
[INFO] [stdout]             ),
[INFO] [stdout]         ),
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 1,
[INFO] [stdout]     },
[INFO] [stdout]     Exit {
[INFO] [stdout]         code: 0,
[INFO] [stdout]     },
[INFO] [stdout] ]
[INFO] [stdout] Executing byte at 0: Nil Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: []
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 1: CreateGlobal { string_index: 0 } Stack: ["nil"]
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: []
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 2: Class { name_index: 1, superclass: Some(Global(0)) } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: reachable: []
[INFO] [stdout] FREEING: []
[INFO] [stdout] [
[INFO] [stdout]     Constant(
[INFO] [stdout]         123.0,
[INFO] [stdout]     ),
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 1,
[INFO] [stdout]         superclass: Some(
[INFO] [stdout]             Global(
[INFO] [stdout]                 0,
[INFO] [stdout]             ),
[INFO] [stdout]         ),
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 1,
[INFO] [stdout]     },
[INFO] [stdout]     Exit {
[INFO] [stdout]         code: 0,
[INFO] [stdout]     },
[INFO] [stdout] ]
[INFO] [stdout] Executing byte at 0: Constant(123.0) Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: []
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 1: CreateGlobal { string_index: 0 } Stack: ["123"]
[INFO] [stdout] GC: checking value "123"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: []
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 2: Class { name_index: 1, superclass: Some(Global(0)) } Stack: []
[INFO] [stdout] GC: checking value "123"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: []
[INFO] [stdout] FREEING: []
[INFO] [stdout] [
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 3,
[INFO] [stdout]         superclass: None,
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 24,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 29,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 3,
[INFO] [stderr] error: test failed, to rerun pass `--bin RustLox`
[INFO] [stdout]     },
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 6,
[INFO] [stdout]         superclass: Some(
[INFO] [stdout]             Global(
[INFO] [stdout]                 3,
[INFO] [stdout]             ),
[INFO] [stdout]         ),
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 34,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 39,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 6,
[INFO] [stdout]     },
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 6,
[INFO] [stdout]     },
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 5,
[INFO] [stdout]     },
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 5,
[INFO] [stdout]     },
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         2,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 5,
[INFO] [stdout]     },
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         4,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 5,
[INFO] [stdout]     },
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Exit {
[INFO] [stdout]         code: 0,
[INFO] [stdout]     },
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 0,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     String(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Print,
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 2,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     String(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Print,
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 4,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     String(
[INFO] [stdout]         5,
[INFO] [stdout]     ),
[INFO] [stdout]     Print,
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 0,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     String(
[INFO] [stdout]         5,
[INFO] [stdout]     ),
[INFO] [stdout]     Print,
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout] ]
[INFO] [stdout] Executing byte at 0: Class { name_index: 3, superclass: None } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: []
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 3: CreateGlobal { string_index: 3 } Stack: ["<class Foo>"]
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn methodOnFoo>", "<class Foo>", "<fn override>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 4: Class { name_index: 6, superclass: Some(Global(3)) } Stack: []
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn methodOnFoo>", "<fn override>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Superclass address: <class Foo>
[INFO] [stdout] Superclass address: <class Foo>
[INFO] [stdout] Executing byte at 7: CreateGlobal { string_index: 6 } Stack: ["<class Bar>"]
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn methodOnFoo>", "<fn methodOnBar>", "<class Foo>", "<fn override>", "<class Bar>", "<fn override>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 8: GetGlobal { string_index: 6 } Stack: []
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Bar>", "<fn override>", "<class Foo>", "<fn override>", "<fn methodOnFoo>", "<fn methodOnBar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 9: Call { arg_count: 0 } Stack: ["<class Bar>"]
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Bar>", "<fn methodOnFoo>", "<fn override>", "<fn override>", "<class Foo>", "<fn methodOnBar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 10: CreateGlobal { string_index: 5 } Stack: ["<Bar instance>"]
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn methodOnBar>", "<fn override>", "<fn override>", "<fn override>", "<class Foo>", "<fn methodOnBar>", "<fn methodOnFoo>", "<Bar instance>", "<fn methodOnFoo>", "<class Bar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 11: GetGlobal { string_index: 5 } Stack: []
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn override>", "<fn methodOnBar>", "<class Bar>", "<Bar instance>", "<class Foo>", "<fn override>", "<fn methodOnBar>", "<fn methodOnFoo>", "<fn override>", "<fn methodOnFoo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 12: DotProperty(2) Stack: ["<Bar instance>"]
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn methodOnFoo>", "<fn methodOnBar>", "<fn methodOnBar>", "<Bar instance>", "<class Foo>", "<fn methodOnFoo>", "<class Bar>", "<fn override>", "<fn override>", "<fn override>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 13: Call { arg_count: 0 } Stack: ["<fn methodOnFoo>"]
[INFO] [stdout] GC: checking value "<fn methodOnFoo>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn methodOnBar>", "<fn methodOnFoo>", "<fn override>", "<fn override>", "<fn methodOnBar>", "<fn methodOnFoo>", "<class Bar>", "<class Foo>", "<fn override>", "<Bar instance>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 30: String(1) Stack: []
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Bar>", "<fn override>", "<fn override>", "<class Foo>", "<fn methodOnFoo>", "<fn methodOnBar>", "<Bar instance>", "<fn override>", "<fn methodOnFoo>", "<fn methodOnBar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 31: Print Stack: ["foo"]
[INFO] [stdout] GC: checking value "foo"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn methodOnFoo>", "<class Bar>", "<fn methodOnBar>", "<fn override>", "<fn override>", "<fn override>", "<fn methodOnBar>", "<fn methodOnFoo>", "<Bar instance>", "foo"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 32: Nil Stack: []
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn override>", "<class Bar>", "<fn override>", "<fn methodOnBar>", "<Bar instance>", "<fn override>", "<fn methodOnBar>", "<fn methodOnFoo>", "<class Foo>", "<fn methodOnFoo>"]
[INFO] [stdout] FREEING: ["foo"]
[INFO] [stdout] Executing byte at 33: Return Stack: ["nil"]
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn override>", "<Bar instance>", "<fn methodOnFoo>", "<class Bar>", "<fn override>", "<fn override>", "<fn methodOnFoo>", "<fn methodOnBar>", "<fn methodOnBar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 14: Pop(1) Stack: ["nil"]
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn methodOnBar>", "<fn methodOnFoo>", "<fn override>", "<fn override>", "<fn override>", "<Bar instance>", "<class Foo>", "<fn methodOnFoo>", "<class Bar>", "<fn methodOnBar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 15: GetGlobal { string_index: 5 } Stack: []
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn methodOnBar>", "<fn override>", "<class Bar>", "<Bar instance>", "<fn override>", "<fn methodOnFoo>", "<fn methodOnBar>", "<fn methodOnFoo>", "<fn override>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 16: DotProperty(4) Stack: ["<Bar instance>"]
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn override>", "<fn methodOnFoo>", "<class Bar>", "<fn methodOnBar>", "<fn methodOnFoo>", "<Bar instance>", "<fn override>", "<fn methodOnBar>", "<fn override>", "<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 17: Call { arg_count: 0 } Stack: ["<fn methodOnBar>"]
[INFO] [stdout] GC: checking value "<fn methodOnBar>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn methodOnFoo>", "<class Bar>", "<fn override>", "<fn methodOnFoo>", "<fn override>", "<fn methodOnBar>", "<class Foo>", "<fn override>", "<Bar instance>", "<fn methodOnBar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 35: String(5) Stack: []
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Bar>", "<fn methodOnBar>", "<fn methodOnFoo>", "<fn override>", "<fn override>", "<class Foo>", "<fn methodOnFoo>", "<fn override>", "<fn methodOnBar>", "<Bar instance>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 36: Print Stack: ["bar"]
[INFO] [stdout] GC: checking value "bar"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn methodOnBar>", "bar", "<fn override>", "<class Foo>", "<fn methodOnFoo>", "<fn methodOnFoo>", "<Bar instance>", "<fn methodOnBar>", "<fn override>", "<class Bar>", "<fn override>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 37: Nil Stack: []
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn methodOnBar>", "<fn override>", "<class Bar>", "<fn methodOnFoo>", "<class Foo>", "<fn override>", "<fn methodOnBar>", "<fn methodOnFoo>", "<Bar instance>", "<fn override>"]
[INFO] [stdout] FREEING: ["bar"]
[INFO] [stdout] Executing byte at 38: Return Stack: ["nil"]
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn methodOnBar>", "<Bar instance>", "<fn methodOnFoo>", "<class Bar>", "<fn methodOnBar>", "<fn override>", "<class Foo>", "<fn override>", "<fn methodOnFoo>", "<fn override>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 18: Pop(1) Stack: ["nil"]
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn methodOnBar>", "<class Foo>", "<class Bar>", "<Bar instance>", "<fn methodOnFoo>", "<fn override>", "<fn methodOnBar>", "<fn methodOnFoo>", "<fn override>", "<fn override>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 19: GetGlobal { string_index: 5 } Stack: []
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn override>", "<fn methodOnFoo>", "<fn methodOnFoo>", "<fn override>", "<fn override>", "<fn methodOnBar>", "<fn methodOnBar>", "<class Bar>", "<Bar instance>", "<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 20: DotProperty(0) Stack: ["<Bar instance>"]
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn methodOnBar>", "<Bar instance>", "<fn methodOnFoo>", "<fn methodOnFoo>", "<fn override>", "<fn methodOnBar>", "<class Bar>", "<class Foo>", "<fn override>", "<fn override>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 21: Call { arg_count: 0 } Stack: ["<fn override>"]
[INFO] [stdout] GC: checking value "<fn override>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn override>", "<fn override>", "<fn methodOnFoo>", "<fn methodOnFoo>", "<fn methodOnBar>", "<fn methodOnBar>", "<class Foo>", "<fn override>", "<Bar instance>", "<class Bar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 40: String(5) Stack: []
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn methodOnFoo>", "<fn override>", "<class Foo>", "<Bar instance>", "<fn methodOnBar>", "<fn methodOnFoo>", "<class Bar>", "<fn override>", "<fn override>", "<fn methodOnBar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 41: Print Stack: ["bar"]
[INFO] [stdout] GC: checking value "bar"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["bar", "<fn methodOnFoo>", "<fn override>", "<Bar instance>", "<class Foo>", "<class Bar>", "<fn override>", "<fn override>", "<fn methodOnBar>", "<fn methodOnBar>", "<fn methodOnFoo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 42: Nil Stack: []
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Bar>", "<Bar instance>", "<fn methodOnBar>", "<fn methodOnFoo>", "<fn methodOnBar>", "<fn override>", "<fn override>", "<fn methodOnFoo>", "<fn override>", "<class Foo>"]
[INFO] [stdout] FREEING: ["bar"]
[INFO] [stdout] Executing byte at 43: Return Stack: ["nil"]
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Bar>", "<fn methodOnBar>", "<fn override>", "<fn override>", "<fn methodOnFoo>", "<fn methodOnBar>", "<class Foo>", "<Bar instance>", "<fn methodOnFoo>", "<fn override>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 22: Pop(1) Stack: ["nil"]
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn methodOnFoo>", "<fn override>", "<fn override>", "<fn override>", "<class Foo>", "<fn methodOnBar>", "<fn methodOnBar>", "<fn methodOnFoo>", "<Bar instance>", "<class Bar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 23: Exit { code: 0 } Stack: []
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn override>", "<fn methodOnBar>", "<fn methodOnFoo>", "<fn methodOnBar>", "<fn override>", "<class Bar>", "<fn override>", "<fn methodOnFoo>", "<Bar instance>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] [
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 1,
[INFO] [stdout]         superclass: Some(
[INFO] [stdout]             Global(
[INFO] [stdout]                 0,
[INFO] [stdout]             ),
[INFO] [stdout]         ),
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 1,
[INFO] [stdout]     },
[INFO] [stdout]     Exit {
[INFO] [stdout]         code: 0,
[INFO] [stdout]     },
[INFO] [stdout] ]
[INFO] [stdout] Executing byte at 0: Class { name_index: 1, superclass: Some(Global(0)) } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: []
[INFO] [stdout] FREEING: []
[INFO] [stdout] [
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 2,
[INFO] [stdout]         superclass: None,
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 25,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 2,
[INFO] [stdout]     },
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 5,
[INFO] [stdout]         superclass: Some(
[INFO] [stdout]             Global(
[INFO] [stdout]                 2,
[INFO] [stdout]             ),
[INFO] [stdout]         ),
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 30,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 5,
[INFO] [stdout]     },
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 8,
[INFO] [stdout]         superclass: Some(
[INFO] [stdout]             Global(
[INFO] [stdout]                 5,
[INFO] [stdout]             ),
[INFO] [stdout]         ),
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 35,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 8,
[INFO] [stdout]     },
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 8,
[INFO] [stdout]     },
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 9,
[INFO] [stdout]     },
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 9,
[INFO] [stdout]     },
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 9,
[INFO] [stdout]     },
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         3,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 9,
[INFO] [stdout]     },
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         6,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Exit {
[INFO] [stdout]         code: 0,
[INFO] [stdout]     },
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 0,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     String(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Print,
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 3,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     String(
[INFO] [stdout]         4,
[INFO] [stdout]     ),
[INFO] [stdout]     Print,
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 6,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     String(
[INFO] [stdout]         7,
[INFO] [stdout]     ),
[INFO] [stdout]     Print,
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout] ]
[INFO] [stdout] Executing byte at 0: Class { name_index: 2, superclass: None } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: []
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 2: CreateGlobal { string_index: 2 } Stack: ["<class Foo>"]
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn inFoo>", "<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 3: Class { name_index: 5, superclass: Some(Global(2)) } Stack: []
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn inFoo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Superclass address: <class Foo>
[INFO] [stdout] Executing byte at 5: CreateGlobal { string_index: 5 } Stack: ["<class Bar>"]
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn inBar>", "<class Bar>", "<fn inFoo>", "<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 6: Class { name_index: 8, superclass: Some(Global(5)) } Stack: []
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn inBar>", "<class Bar>", "<class Foo>", "<fn inFoo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Superclass address: <class Bar>
[INFO] [stdout] Executing byte at 8: CreateGlobal { string_index: 8 } Stack: ["<class Baz>"]
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn inBaz>", "<class Baz>", "<class Bar>", "<fn inFoo>", "<fn inBar>", "<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 9: GetGlobal { string_index: 8 } Stack: []
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn inBar>", "<class Bar>", "<class Baz>", "<fn inFoo>", "<fn inBaz>", "<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 10: Call { arg_count: 0 } Stack: ["<class Baz>"]
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn inBar>", "<class Foo>", "<class Bar>", "<fn inBaz>", "<fn inFoo>", "<class Baz>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 11: CreateGlobal { string_index: 9 } Stack: ["<Baz instance>"]
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn inBar>", "<class Foo>", "<fn inBaz>", "<class Baz>", "<fn inBaz>", "<fn inFoo>", "<Baz instance>", "<fn inFoo>", "<fn inBar>", "<class Bar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 12: GetGlobal { string_index: 9 } Stack: []
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn inBar>", "<fn inBar>", "<fn inBaz>", "<fn inFoo>", "<fn inBaz>", "<class Bar>", "<fn inFoo>", "<Baz instance>", "<class Baz>", "<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 13: DotProperty(0) Stack: ["<Baz instance>"]
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn inBaz>", "<class Bar>", "<fn inBaz>", "<fn inBar>", "<fn inFoo>", "<fn inFoo>", "<fn inBar>", "<Baz instance>", "<class Foo>", "<class Baz>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 14: Call { arg_count: 0 } Stack: ["<fn inFoo>"]
[INFO] [stdout] GC: checking value "<fn inFoo>"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn inBaz>", "<fn inBaz>", "<fn inBar>", "<class Foo>", "<class Baz>", "<class Bar>", "<fn inFoo>", "<fn inBar>", "<fn inFoo>", "<Baz instance>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 26: String(1) Stack: []
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<Baz instance>", "<fn inBaz>", "<class Bar>", "<fn inBaz>", "<fn inFoo>", "<fn inBar>", "<fn inFoo>", "<class Baz>", "<fn inBar>", "<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 27: Print Stack: ["in foo"]
[INFO] [stdout] GC: checking value "in foo"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<class Baz>", "<fn inBaz>", "<fn inBar>", "<fn inBaz>", "<class Foo>", "<fn inBar>", "<fn inFoo>", "<Baz instance>", "<class Bar>", "<fn inFoo>", "in foo"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 28: Nil Stack: []
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn inBaz>", "<class Bar>", "<fn inBar>", "<fn inFoo>", "<class Baz>", "<fn inBar>", "<Baz instance>", "<fn inBaz>", "<fn inFoo>", "<class Foo>"]
[INFO] [stdout] FREEING: ["in foo"]
[INFO] [stdout] Executing byte at 29: Return Stack: ["nil"]
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn inBaz>", "<fn inBar>", "<Baz instance>", "<class Foo>", "<fn inBaz>", "<class Baz>", "<class Bar>", "<fn inFoo>", "<fn inBar>", "<fn inFoo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 15: Pop(1) Stack: ["nil"]
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<class Baz>", "<Baz instance>", "<fn inBaz>", "<class Bar>", "<fn inFoo>", "<fn inBar>", "<fn inBar>", "<fn inFoo>", "<class Foo>", "<fn inBaz>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 16: GetGlobal { string_index: 9 } Stack: []
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<class Baz>", "<fn inBar>", "<fn inBar>", "<class Bar>", "<class Foo>", "<Baz instance>", "<fn inBaz>", "<fn inFoo>", "<fn inBaz>", "<fn inFoo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 17: DotProperty(3) Stack: ["<Baz instance>"]
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<Baz instance>", "<class Bar>", "<fn inBaz>", "<fn inFoo>", "<class Foo>", "<fn inFoo>", "<fn inBar>", "<class Baz>", "<fn inBar>", "<fn inBaz>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 18: Call { arg_count: 0 } Stack: ["<fn inBar>"]
[INFO] [stdout] GC: checking value "<fn inBar>"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<Baz instance>", "<fn inBar>", "<fn inBaz>", "<class Bar>", "<fn inFoo>", "<class Foo>", "<fn inBaz>", "<fn inBar>", "<class Baz>", "<fn inFoo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 31: String(4) Stack: []
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn inBaz>", "<class Baz>", "<fn inFoo>", "<fn inFoo>", "<Baz instance>", "<class Bar>", "<fn inBaz>", "<fn inBar>", "<class Foo>", "<fn inBar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 32: Print Stack: ["in bar"]
[INFO] [stdout] GC: checking value "in bar"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<class Baz>", "<Baz instance>", "<fn inBar>", "<fn inBar>", "<fn inBaz>", "<class Foo>", "<fn inFoo>", "<class Bar>", "in bar", "<fn inBaz>", "<fn inFoo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 33: Nil Stack: []
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn inBaz>", "<fn inFoo>", "<fn inFoo>", "<fn inBar>", "<fn inBar>", "<class Bar>", "<class Foo>", "<Baz instance>", "<class Baz>", "<fn inBaz>"]
[INFO] [stdout] FREEING: ["in bar"]
[INFO] [stdout] Executing byte at 34: Return Stack: ["nil"]
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn inBar>", "<Baz instance>", "<fn inFoo>", "<fn inBaz>", "<class Baz>", "<fn inFoo>", "<class Bar>", "<class Foo>", "<fn inBar>", "<fn inBaz>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 19: Pop(1) Stack: ["nil"]
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<Baz instance>", "<fn inFoo>", "<class Bar>", "<fn inBar>", "<fn inBaz>", "<class Foo>", "<fn inFoo>", "<fn inBar>", "<class Baz>", "<fn inBaz>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 20: GetGlobal { string_index: 9 } Stack: []
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<class Bar>", "<fn inBar>", "<fn inBar>", "<fn inBaz>", "<fn inFoo>", "<fn inBaz>", "<class Foo>", "<class Baz>", "<fn inFoo>", "<Baz instance>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 21: DotProperty(6) Stack: ["<Baz instance>"]
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<class Bar>", "<fn inFoo>", "<fn inBar>", "<fn inBaz>", "<Baz instance>", "<fn inFoo>", "<fn inBar>", "<class Foo>", "<fn inBaz>", "<class Baz>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 22: Call { arg_count: 0 } Stack: ["<fn inBaz>"]
[INFO] [stdout] GC: checking value "<fn inBaz>"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn inBaz>", "<class Bar>", "<fn inBaz>", "<fn inBar>", "<fn inFoo>", "<Baz instance>", "<fn inBar>", "<class Foo>", "<fn inFoo>", "<class Baz>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 36: String(7) Stack: []
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn inBaz>", "<Baz instance>", "<fn inBaz>", "<fn inFoo>", "<class Foo>", "<fn inFoo>", "<class Baz>", "<class Bar>", "<fn inBar>", "<fn inBar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 37: Print Stack: ["in baz"]
[INFO] [stdout] GC: checking value "in baz"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn inBaz>", "<Baz instance>", "<fn inBar>", "<class Foo>", "<class Bar>", "<fn inFoo>", "<class Baz>", "in baz", "<fn inBaz>", "<fn inFoo>", "<fn inBar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 38: Nil Stack: []
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn inFoo>", "<fn inBar>", "<class Foo>", "<fn inFoo>", "<fn inBar>", "<Baz instance>", "<class Baz>", "<fn inBaz>", "<class Bar>", "<fn inBaz>"]
[INFO] [stdout] FREEING: ["in baz"]
[INFO] [stdout] Executing byte at 39: Return Stack: ["nil"]
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn inFoo>", "<class Foo>", "<fn inBar>", "<fn inBar>", "<class Baz>", "<fn inBaz>", "<class Bar>", "<fn inBaz>", "<fn inFoo>", "<Baz instance>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 23: Pop(1) Stack: ["nil"]
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn inBar>", "<Baz instance>", "<class Bar>", "<fn inBaz>", "<fn inFoo>", "<fn inBaz>", "<fn inBar>", "<class Foo>", "<class Baz>", "<fn inFoo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 24: Exit { code: 0 } Stack: []
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn inFoo>", "<fn inFoo>", "<fn inBar>", "<class Bar>", "<class Foo>", "<fn inBaz>", "<class Baz>", "<fn inBaz>", "<Baz instance>", "<fn inBar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] [
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 0,
[INFO] [stdout]         superclass: None,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 8,
[INFO] [stdout]         type_: Function,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 1,
[INFO] [stdout]     },
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 1,
[INFO] [stdout]     },
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Print,
[INFO] [stdout]     Exit {
[INFO] [stdout]         code: 0,
[INFO] [stdout]     },
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 1,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 2,
[INFO] [stdout]         superclass: Some(
[INFO] [stdout]             Global(
[INFO] [stdout]                 0,
[INFO] [stdout]             ),
[INFO] [stdout]         ),
[INFO] [stdout]     },
[INFO] [stdout]     GetLocal(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Return,
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout] ]
[INFO] [stdout] Executing byte at 0: Class { name_index: 0, superclass: None } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: []
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 1: CreateGlobal { string_index: 0 } Stack: ["<class A>"]
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class A>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 2: Callable { address: 8, type_: Function } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: reachable: ["<class A>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 3: CreateGlobal { string_index: 1 } Stack: ["<fn f>"]
[INFO] [stdout] GC: checking value "<fn f>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: reachable: ["<fn f>", "<class A>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 4: GetGlobal { string_index: 1 } Stack: []
[INFO] [stdout] GC: checking value "<fn f>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: reachable: ["<fn f>", "<class A>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 5: Call { arg_count: 0 } Stack: ["<fn f>"]
[INFO] [stdout] GC: checking value "<fn f>"
[INFO] [stdout] GC: checking value "<fn f>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: reachable: ["<fn f>", "<class A>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 9: Class { name_index: 2, superclass: Some(Global(0)) } Stack: ["<fn f>"]
[INFO] [stdout] GC: checking value "<fn f>"
[INFO] [stdout] GC: checking value "<fn f>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: reachable: ["<class A>", "<fn f>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 10: GetLocal(1) Stack: ["<fn f>", "<class B>"]
[INFO] [stdout] GC: checking value "<fn f>"
[INFO] [stdout] GC: checking value "<class B>"
[INFO] [stdout] GC: checking value "<fn f>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: reachable: ["<class A>", "<fn f>", "<class B>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 11: Return Stack: ["<fn f>", "<class B>", "<class B>"]
[INFO] [stdout] GC: checking value "<fn f>"
[INFO] [stdout] GC: checking value "<class B>"
[INFO] [stdout] GC: checking value "<class B>"
[INFO] [stdout] GC: checking value "<fn f>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: reachable: ["<class A>", "<fn f>", "<class B>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 6: Print Stack: ["<class B>"]
[INFO] [stdout] GC: checking value "<class B>"
[INFO] [stdout] GC: checking value "<fn f>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: reachable: ["<class A>", "<class B>", "<fn f>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 7: Exit { code: 0 } Stack: []
[INFO] [stdout] GC: checking value "<fn f>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: reachable: ["<fn f>", "<class A>"]
[INFO] [stdout] FREEING: ["<class B>"]
[INFO] [stdout] [
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 3,
[INFO] [stdout]         superclass: None,
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 15,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 21,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 3,
[INFO] [stdout]     },
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 4,
[INFO] [stdout]         superclass: Some(
[INFO] [stdout]             Global(
[INFO] [stdout]                 3,
[INFO] [stdout]             ),
[INFO] [stdout]         ),
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 4,
[INFO] [stdout]     },
[INFO] [stdout]     String(
[INFO] [stdout]         5,
[INFO] [stdout]     ),
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 4,
[INFO] [stdout]     },
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 1,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 6,
[INFO] [stdout]     },
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 6,
[INFO] [stdout]     },
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Exit {
[INFO] [stdout]         code: 0,
[INFO] [stdout]     },
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 0,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Print,
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 2,
[INFO] [stdout]         arg_count: 1,
[INFO] [stdout]     },
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     GetLocal(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     AssignField {
[INFO] [stdout]         field_name_index: 1,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     Return,
[INFO] [stdout] ]
[INFO] [stdout] Executing byte at 0: Class { name_index: 3, superclass: None } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: []
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 3: CreateGlobal { string_index: 3 } Stack: ["<class A>"]
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn test>", "<class A>", "<fn init>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 4: Class { name_index: 4, superclass: Some(Global(3)) } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: reachable: ["<fn test>", "<class A>", "<fn init>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 5: CreateGlobal { string_index: 4 } Stack: ["<class B>"]
[INFO] [stdout] GC: checking value "<class B>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: reachable: ["<fn init>", "<class B>", "<class A>", "<fn test>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 6: String(5) Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class B>"
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: reachable: ["<fn init>", "<class A>", "<class B>", "<fn test>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 7: GetGlobal { string_index: 4 } Stack: ["value"]
[INFO] [stdout] GC: checking value "value"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class B>"
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: reachable: ["<class A>", "<fn init>", "value", "<class B>", "<fn test>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 8: Call { arg_count: 1 } Stack: ["value", "<class B>"]
[INFO] [stdout] GC: checking value "value"
[INFO] [stdout] GC: checking value "<class B>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class B>"
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: reachable: ["<fn init>", "<class B>", "<fn test>", "value", "<class A>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 22: GetCaptured(0) Stack: ["value"]
[INFO] [stdout] GC: checking value "value"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class B>"
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: reachable: ["<fn init>", "<fn test>", "<class A>", "value", "<class B>"]
[INFO] [stdout] FREEING: ["<fn test>", "<fn init>", "<B instance>"]
[INFO] [stdout] 
[INFO] [stdout] thread 'test::test_module::case_14_inheritance' (32) panicked at src/heap.rs:121:14:
[INFO] [stdout] Use after free: invalid address
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5fe39a7cb55a - std[fce30c26ee6459b]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x5fe39a7cb55a - std[fce30c26ee6459b]::backtrace_rs::backtrace::trace_unsynchronized::<std[fce30c26ee6459b]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x5fe39a7cb55a - std[fce30c26ee6459b]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x5fe39a7cb55a - <<std[fce30c26ee6459b]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[d17f6591d688f3bd]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x5fe39a7e272a - <core[d17f6591d688f3bd]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x5fe39a7e272a - core[d17f6591d688f3bd]::fmt::write
[INFO] [stdout]    6:     0x5fe39a7cffe2 - std[fce30c26ee6459b]::io::default_write_fmt::<alloc[dad09f3a48280dba]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/io/mod.rs:621:11
[INFO] [stdout]    7:     0x5fe39a7cffe2 - <alloc[dad09f3a48280dba]::vec::Vec<u8> as std[fce30c26ee6459b]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/io/mod.rs:1976:13
[INFO] [stdout]    8:     0x5fe39a7a9d6f - <std[fce30c26ee6459b]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x5fe39a7a9d6f - std[fce30c26ee6459b]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x5fe39a7c3409 - std[fce30c26ee6459b]::panicking::default_hook
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x5fe39a4e710c - <alloc[dad09f3a48280dba]::boxed::Box<dyn for<'a, 'b> core[d17f6591d688f3bd]::ops::function::Fn<(&'a std[fce30c26ee6459b]::panic::PanicHookInfo<'b>,), Output = ()> + core[d17f6591d688f3bd]::marker::Sync + core[d17f6591d688f3bd]::marker::Send> as core[d17f6591d688f3bd]::ops::function::Fn<(&std[fce30c26ee6459b]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/alloc/src/boxed.rs:2285:9
[INFO] [stdout]   12:     0x5fe39a4e710c - test[9653f4f1d2ca1489]::test_main_inner::<test[9653f4f1d2ca1489]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:155:21
[INFO] [stdout]   13:     0x5fe39a7c35c2 - <alloc[dad09f3a48280dba]::boxed::Box<dyn for<'a, 'b> core[d17f6591d688f3bd]::ops::function::Fn<(&'a std[fce30c26ee6459b]::panic::PanicHookInfo<'b>,), Output = ()> + core[d17f6591d688f3bd]::marker::Sync + core[d17f6591d688f3bd]::marker::Send> as core[d17f6591d688f3bd]::ops::function::Fn<(&std[fce30c26ee6459b]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/alloc/src/boxed.rs:2285:9
[INFO] [stdout]   14:     0x5fe39a7c35c2 - std[fce30c26ee6459b]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x5fe39a7a9e28 - std[fce30c26ee6459b]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:698:13
[INFO] [stdout]   16:     0x5fe39a79ee59 - std[fce30c26ee6459b]::sys::backtrace::__rust_end_short_backtrace::<std[fce30c26ee6459b]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x5fe39a7aab3d - __rustc[de152adb51f02556]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x5fe39a7e2f6c - core[d17f6591d688f3bd]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x5fe39a7e2c64 - core[d17f6591d688f3bd]::panicking::panic_display::<&str>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/panicking.rs:259:5
[INFO] [stdout]   20:     0x5fe39a7e2c64 - core[d17f6591d688f3bd]::option::expect_failed
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/option.rs:2260:5
[INFO] [stdout]   21:     0x5fe39a4ae5c0 - <core[d17f6591d688f3bd]::option::Option<&RustLox[3daf0c3e01451a46]::heap::HeapValue>>::expect
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/option.rs:971:21
[INFO] [stdout]   22:     0x5fe39a4cdb2e - <RustLox[3daf0c3e01451a46]::heap::Heap>::get
[INFO] [stdout]                                at /opt/rustwide/workdir/src/heap.rs:121:14
[INFO] [stdout]   23:     0x5fe39a49cbdd - <RustLox[3daf0c3e01451a46]::value::Value>::deref
[INFO] [stdout]                                at /opt/rustwide/workdir/src/value.rs:103:51
[INFO] [stdout]   24:     0x5fe39a49f36a - <RustLox[3daf0c3e01451a46]::interpreter::Interpreter<&mut alloc[dad09f3a48280dba]::string::String, &mut alloc[dad09f3a48280dba]::string::String>>::get_captured
[INFO] [stdout]                                at /opt/rustwide/workdir/src/interpreter.rs:188:42
[INFO] [stdout]   25:     0x5fe39a4a1972 - <RustLox[3daf0c3e01451a46]::interpreter::Interpreter<&mut alloc[dad09f3a48280dba]::string::String, &mut alloc[dad09f3a48280dba]::string::String>>::interpret_unsafe
[INFO] [stdout]                                at /opt/rustwide/workdir/src/interpreter.rs:664:38
[INFO] [stdout]   26:     0x5fe39a4abdda - <RustLox[3daf0c3e01451a46]::interpreter::Interpreter<&mut alloc[dad09f3a48280dba]::string::String, &mut alloc[dad09f3a48280dba]::string::String>>::interpret
[INFO] [stdout]                                at /opt/rustwide/workdir/src/interpreter.rs:866:20
[INFO] [stdout]   27:     0x5fe39a4c3a36 - RustLox[3daf0c3e01451a46]::test::test_interpret_file::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:100:29
[INFO] [stdout]   28:     0x5fe39a4c863a - <RustLox[3daf0c3e01451a46]::test::test_interpret_file::{closure#0} as core[d17f6591d688f3bd]::ops::function::FnOnce<(&str,)>>::call_once
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   29:     0x5fe39a4c6321 - RustLox[3daf0c3e01451a46]::test::test_file
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:38:18
[INFO] [stdout]   30:     0x5fe39a4c60aa - RustLox[3daf0c3e01451a46]::test::test_interpret_file
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:92:5
[INFO] [stdout]   31:     0x5fe39a4c4fe6 - RustLox[3daf0c3e01451a46]::test::test_module
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:160:9
[INFO] [stdout]   32:     0x5fe39a4ce392 - RustLox[3daf0c3e01451a46]::test::test_module::case_14_inheritance
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:125:1
[INFO] [stdout]   33:     0x5fe39a4ca9c7 - RustLox[3daf0c3e01451a46]::test::test_module::case_14_inheritance::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:125:10
[INFO] [stdout]   34:     0x5fe39a4c8cf6 - <RustLox[3daf0c3e01451a46]::test::test_module::case_14_inheritance::{closure#0} as core[d17f6591d688f3bd]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   35:     0x5fe39a4da3eb - <fn() -> core[d17f6591d688f3bd]::result::Result<(), alloc[dad09f3a48280dba]::string::String> as core[d17f6591d688f3bd]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   36:     0x5fe39a4da3eb - test[9653f4f1d2ca1489]::__rust_begin_short_backtrace::<core[d17f6591d688f3bd]::result::Result<(), alloc[dad09f3a48280dba]::string::String>, fn() -> core[d17f6591d688f3bd]::result::Result<(), alloc[dad09f3a48280dba]::string::String>>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:724:18
[INFO] [stdout]   37:     0x5fe39a4e7bdb - test[9653f4f1d2ca1489]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:747:74
[INFO] [stdout]   38:     0x5fe39a4e7bdb - <core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<test[9653f4f1d2ca1489]::run_test_in_process::{closure#0}> as core[d17f6591d688f3bd]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   39:     0x5fe39a4e7bdb - std[fce30c26ee6459b]::panicking::catch_unwind::do_call::<core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<test[9653f4f1d2ca1489]::run_test_in_process::{closure#0}>, core[d17f6591d688f3bd]::result::Result<(), alloc[dad09f3a48280dba]::string::String>>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:581:40
[INFO] [stdout]   40:     0x5fe39a4e7bdb - std[fce30c26ee6459b]::panicking::catch_unwind::<core[d17f6591d688f3bd]::result::Result<(), alloc[dad09f3a48280dba]::string::String>, core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<test[9653f4f1d2ca1489]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:544:19
[INFO] [stdout]   41:     0x5fe39a4e7bdb - std[fce30c26ee6459b]::panic::catch_unwind::<core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<test[9653f4f1d2ca1489]::run_test_in_process::{closure#0}>, core[d17f6591d688f3bd]::result::Result<(), alloc[dad09f3a48280dba]::string::String>>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panic.rs:359:14
[INFO] [stdout]   42:     0x5fe39a4e7bdb - test[9653f4f1d2ca1489]::run_test_in_process
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:747:27
[INFO] [stdout]   43:     0x5fe39a4e7bdb - test[9653f4f1d2ca1489]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:668:43
[INFO] [stdout]   44:     0x5fe39a4e12f4 - test[9653f4f1d2ca1489]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:698:41
[INFO] [stdout]   45:     0x5fe39a4e12f4 - std[fce30c26ee6459b]::sys::backtrace::__rust_begin_short_backtrace::<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   46:     0x5fe39a4ea7e2 - std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked::<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   47:     0x5fe39a4ea7e2 - <core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[d17f6591d688f3bd]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   48:     0x5fe39a4ea7e2 - std[fce30c26ee6459b]::panicking::catch_unwind::do_call::<core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:581:40
[INFO] [stdout]   49:     0x5fe39a4ea7e2 - std[fce30c26ee6459b]::panicking::catch_unwind::<(), core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:544:19
[INFO] [stdout]   50:     0x5fe39a4ea7e2 - std[fce30c26ee6459b]::panic::catch_unwind::<core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panic.rs:359:14
[INFO] [stdout]   51:     0x5fe39a4ea7e2 - std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked::<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   52:     0x5fe39a4ea7e2 - <std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1} as core[d17f6591d688f3bd]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   53:     0x5fe39a7cab0f - <alloc[dad09f3a48280dba]::boxed::Box<dyn core[d17f6591d688f3bd]::ops::function::FnOnce<(), Output = ()> + core[d17f6591d688f3bd]::marker::Send> as core[d17f6591d688f3bd]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/alloc/src/boxed.rs:2271:9
[INFO] [stdout]   54:     0x5fe39a7cab0f - <std[fce30c26ee6459b]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   55:     0x74feac48baa4 - <unknown>
[INFO] [stdout]   56:     0x74feac518a64 - clone
[INFO] [stdout]   57:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- test::test_module::case_25_this stdout ----
[INFO] [stdout] [
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 5,
[INFO] [stdout]         superclass: None,
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 37,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 43,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 5,
[INFO] [stdout]     },
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 5,
[INFO] [stdout]     },
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 6,
[INFO] [stdout]     },
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 6,
[INFO] [stdout]     },
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Print,
[INFO] [stdout]     Exit {
[INFO] [stdout]         code: 0,
[INFO] [stdout]     },
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 3,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Capture(
[INFO] [stdout]         Captured(
[INFO] [stdout]             0,
[INFO] [stdout]         ),
[INFO] [stdout]     ),
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         4,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Return,
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 2,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Capture(
[INFO] [stdout]         Captured(
[INFO] [stdout]             0,
[INFO] [stdout]         ),
[INFO] [stdout]     ),
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 15,
[INFO] [stdout]         type_: Function,
[INFO] [stdout]     },
[INFO] [stdout]     GetLocal(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Return,
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 1,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Capture(
[INFO] [stdout]         Captured(
[INFO] [stdout]             0,
[INFO] [stdout]         ),
[INFO] [stdout]     ),
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 23,
[INFO] [stdout]         type_: Function,
[INFO] [stdout]     },
[INFO] [stdout]     GetLocal(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Return,
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 0,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 30,
[INFO] [stdout]         type_: Function,
[INFO] [stdout]     },
[INFO] [stdout]     GetLocal(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     Return,
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 4,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     String(
[INFO] [stdout]         5,
[INFO] [stdout]     ),
[INFO] [stdout]     Return,
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout] ]
[INFO] [stdout] Executing byte at 0: Class { name_index: 5, superclass: None } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: []
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 3: CreateGlobal { string_index: 5 } Stack: ["<class Foo>"]
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn toString>", "<class Foo>", "<fn getClosure>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 4: GetGlobal { string_index: 5 } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn getClosure>", "<fn toString>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 5: Call { arg_count: 0 } Stack: ["<class Foo>"]
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn toString>", "<class Foo>", "<fn getClosure>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 6: DotProperty(0) Stack: ["<Foo instance>"]
[INFO] [stdout] GC: checking value "<Foo instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn getClosure>", "<Foo instance>", "<fn getClosure>", "<class Foo>", "<fn toString>", "<fn toString>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 7: Call { arg_count: 0 } Stack: ["<fn getClosure>"]
[INFO] [stdout] GC: checking value "<fn getClosure>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn getClosure>", "<Foo instance>", "<class Foo>", "<fn toString>", "<fn toString>", "<fn getClosure>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 38: Callable { address: 30, type_: Function } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn toString>", "<class Foo>", "<fn getClosure>"]
[INFO] [stdout] FREEING: ["<fn getClosure>", "<fn toString>", "<Foo instance>"]
[INFO] [stdout] Executing byte at 39: GetLocal(0) Stack: ["<fn f>"]
[INFO] [stdout] GC: checking value "<fn f>"
[INFO] [stdout] 
[INFO] [stdout] thread 'test::test_module::case_25_this' (43) panicked at src/heap.rs:121:14:
[INFO] [stdout] Use after free: invalid address
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5fe39a7cb55a - std[fce30c26ee6459b]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x5fe39a7cb55a - std[fce30c26ee6459b]::backtrace_rs::backtrace::trace_unsynchronized::<std[fce30c26ee6459b]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x5fe39a7cb55a - std[fce30c26ee6459b]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x5fe39a7cb55a - <<std[fce30c26ee6459b]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[d17f6591d688f3bd]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x5fe39a7e272a - <core[d17f6591d688f3bd]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x5fe39a7e272a - core[d17f6591d688f3bd]::fmt::write
[INFO] [stdout]    6:     0x5fe39a7cffe2 - std[fce30c26ee6459b]::io::default_write_fmt::<alloc[dad09f3a48280dba]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/io/mod.rs:621:11
[INFO] [stdout]    7:     0x5fe39a7cffe2 - <alloc[dad09f3a48280dba]::vec::Vec<u8> as std[fce30c26ee6459b]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/io/mod.rs:1976:13
[INFO] [stdout]    8:     0x5fe39a7a9d6f - <std[fce30c26ee6459b]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x5fe39a7a9d6f - std[fce30c26ee6459b]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x5fe39a7c3409 - std[fce30c26ee6459b]::panicking::default_hook
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x5fe39a4e710c - <alloc[dad09f3a48280dba]::boxed::Box<dyn for<'a, 'b> core[d17f6591d688f3bd]::ops::function::Fn<(&'a std[fce30c26ee6459b]::panic::PanicHookInfo<'b>,), Output = ()> + core[d17f6591d688f3bd]::marker::Sync + core[d17f6591d688f3bd]::marker::Send> as core[d17f6591d688f3bd]::ops::function::Fn<(&std[fce30c26ee6459b]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/alloc/src/boxed.rs:2285:9
[INFO] [stdout]   12:     0x5fe39a4e710c - test[9653f4f1d2ca1489]::test_main_inner::<test[9653f4f1d2ca1489]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:155:21
[INFO] [stdout]   13:     0x5fe39a7c35c2 - <alloc[dad09f3a48280dba]::boxed::Box<dyn for<'a, 'b> core[d17f6591d688f3bd]::ops::function::Fn<(&'a std[fce30c26ee6459b]::panic::PanicHookInfo<'b>,), Output = ()> + core[d17f6591d688f3bd]::marker::Sync + core[d17f6591d688f3bd]::marker::Send> as core[d17f6591d688f3bd]::ops::function::Fn<(&std[fce30c26ee6459b]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/alloc/src/boxed.rs:2285:9
[INFO] [stdout]   14:     0x5fe39a7c35c2 - std[fce30c26ee6459b]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x5fe39a7a9e28 - std[fce30c26ee6459b]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:698:13
[INFO] [stdout]   16:     0x5fe39a79ee59 - std[fce30c26ee6459b]::sys::backtrace::__rust_end_short_backtrace::<std[fce30c26ee6459b]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x5fe39a7aab3d - __rustc[de152adb51f02556]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x5fe39a7e2f6c - core[d17f6591d688f3bd]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x5fe39a7e2c64 - core[d17f6591d688f3bd]::panicking::panic_display::<&str>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/panicking.rs:259:5
[INFO] [stdout]   20:     0x5fe39a7e2c64 - core[d17f6591d688f3bd]::option::expect_failed
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/option.rs:2260:5
[INFO] [stdout]   21:     0x5fe39a4ae5c0 - <core[d17f6591d688f3bd]::option::Option<&RustLox[3daf0c3e01451a46]::heap::HeapValue>>::expect
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/option.rs:971:21
[INFO] [stdout]   22:     0x5fe39a4cdb2e - <RustLox[3daf0c3e01451a46]::heap::Heap>::get
[INFO] [stdout]                                at /opt/rustwide/workdir/src/heap.rs:121:14
[INFO] [stdout]   23:     0x5fe39a4ce7b2 - <RustLox[3daf0c3e01451a46]::heap::Heap>::gc::dfs
[INFO] [stdout]                                at /opt/rustwide/workdir/src/heap.rs:136:35
[INFO] [stdout]   24:     0x5fe39a4ce874 - <RustLox[3daf0c3e01451a46]::heap::Heap>::gc::dfs
[INFO] [stdout]                                at /opt/rustwide/workdir/src/heap.rs:137:17
[INFO] [stdout]   25:     0x5fe39a4c992e - <RustLox[3daf0c3e01451a46]::heap::Heap>::gc::<core[d17f6591d688f3bd]::iter::adapters::chain::Chain<core[d17f6591d688f3bd]::slice::iter::Iter<RustLox[3daf0c3e01451a46]::value::Value>, std[fce30c26ee6459b]::collections::hash::map::Values<alloc[dad09f3a48280dba]::string::String, RustLox[3daf0c3e01451a46]::value::Value>>>
[INFO] [stdout]                                at /opt/rustwide/workdir/src/heap.rs:146:17
[INFO] [stdout]   26:     0x5fe39a4a168e - <RustLox[3daf0c3e01451a46]::interpreter::Interpreter<&mut alloc[dad09f3a48280dba]::string::String, &mut alloc[dad09f3a48280dba]::string::String>>::interpret_unsafe
[INFO] [stdout]                                at /opt/rustwide/workdir/src/interpreter.rs:438:23
[INFO] [stdout]   27:     0x5fe39a4abdda - <RustLox[3daf0c3e01451a46]::interpreter::Interpreter<&mut alloc[dad09f3a48280dba]::string::String, &mut alloc[dad09f3a48280dba]::string::String>>::interpret
[INFO] [stdout]                                at /opt/rustwide/workdir/src/interpreter.rs:866:20
[INFO] [stdout]   28:     0x5fe39a4c3a36 - RustLox[3daf0c3e01451a46]::test::test_interpret_file::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:100:29
[INFO] [stdout]   29:     0x5fe39a4c863a - <RustLox[3daf0c3e01451a46]::test::test_interpret_file::{closure#0} as core[d17f6591d688f3bd]::ops::function::FnOnce<(&str,)>>::call_once
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   30:     0x5fe39a4c6321 - RustLox[3daf0c3e01451a46]::test::test_file
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:38:18
[INFO] [stdout]   31:     0x5fe39a4c60aa - RustLox[3daf0c3e01451a46]::test::test_interpret_file
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:92:5
[INFO] [stdout]   32:     0x5fe39a4c4fe6 - RustLox[3daf0c3e01451a46]::test::test_module
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:160:9
[INFO] [stdout]   33:     0x5fe39a4cde92 - RustLox[3daf0c3e01451a46]::test::test_module::case_25_this
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:125:1
[INFO] [stdout]   34:     0x5fe39a4ca607 - RustLox[3daf0c3e01451a46]::test::test_module::case_25_this::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:125:10
[INFO] [stdout]   35:     0x5fe39a4c87f6 - <RustLox[3daf0c3e01451a46]::test::test_module::case_25_this::{closure#0} as core[d17f6591d688f3bd]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   36:     0x5fe39a4da3eb - <fn() -> core[d17f6591d688f3bd]::result::Result<(), alloc[dad09f3a48280dba]::string::String> as core[d17f6591d688f3bd]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   37:     0x5fe39a4da3eb - test[9653f4f1d2ca1489]::__rust_begin_short_backtrace::<core[d17f6591d688f3bd]::result::Result<(), alloc[dad09f3a48280dba]::string::String>, fn() -> core[d17f6591d688f3bd]::result::Result<(), alloc[dad09f3a48280dba]::string::String>>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:724:18
[INFO] [stdout]   38:     0x5fe39a4e7bdb - test[9653f4f1d2ca1489]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:747:74
[INFO] [stdout]   39:     0x5fe39a4e7bdb - <core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<test[9653f4f1d2ca1489]::run_test_in_process::{closure#0}> as core[d17f6591d688f3bd]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   40:     0x5fe39a4e7bdb - std[fce30c26ee6459b]::panicking::catch_unwind::do_call::<core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<test[9653f4f1d2ca1489]::run_test_in_process::{closure#0}>, core[d17f6591d688f3bd]::result::Result<(), alloc[dad09f3a48280dba]::string::String>>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:581:40
[INFO] [stdout]   41:     0x5fe39a4e7bdb - std[fce30c26ee6459b]::panicking::catch_unwind::<core[d17f6591d688f3bd]::result::Result<(), alloc[dad09f3a48280dba]::string::String>, core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<test[9653f4f1d2ca1489]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:544:19
[INFO] [stdout]   42:     0x5fe39a4e7bdb - std[fce30c26ee6459b]::panic::catch_unwind::<core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<test[9653f4f1d2ca1489]::run_test_in_process::{closure#0}>, core[d17f6591d688f3bd]::result::Result<(), alloc[dad09f3a48280dba]::string::String>>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panic.rs:359:14
[INFO] [stdout]   43:     0x5fe39a4e7bdb - test[9653f4f1d2ca1489]::run_test_in_process
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:747:27
[INFO] [stdout]   44:     0x5fe39a4e7bdb - test[9653f4f1d2ca1489]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:668:43
[INFO] [stdout]   45:     0x5fe39a4e12f4 - test[9653f4f1d2ca1489]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/test/src/lib.rs:698:41
[INFO] [stdout]   46:     0x5fe39a4e12f4 - std[fce30c26ee6459b]::sys::backtrace::__rust_begin_short_backtrace::<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   47:     0x5fe39a4ea7e2 - std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked::<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   48:     0x5fe39a4ea7e2 - <core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[d17f6591d688f3bd]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   49:     0x5fe39a4ea7e2 - std[fce30c26ee6459b]::panicking::catch_unwind::do_call::<core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:581:40
[INFO] [stdout]   50:     0x5fe39a4ea7e2 - std[fce30c26ee6459b]::panicking::catch_unwind::<(), core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panicking.rs:544:19
[INFO] [stdout]   51:     0x5fe39a4ea7e2 - std[fce30c26ee6459b]::panic::catch_unwind::<core[d17f6591d688f3bd]::panic::unwind_safe::AssertUnwindSafe<std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/panic.rs:359:14
[INFO] [stdout]   52:     0x5fe39a4ea7e2 - std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked::<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   53:     0x5fe39a4ea7e2 - <std[fce30c26ee6459b]::thread::lifecycle::spawn_unchecked<test[9653f4f1d2ca1489]::run_test::{closure#1}, ()>::{closure#1} as core[d17f6591d688f3bd]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   54:     0x5fe39a7cab0f - <alloc[dad09f3a48280dba]::boxed::Box<dyn core[d17f6591d688f3bd]::ops::function::FnOnce<(), Output = ()> + core[d17f6591d688f3bd]::marker::Send> as core[d17f6591d688f3bd]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/alloc/src/boxed.rs:2271:9
[INFO] [stdout]   55:     0x5fe39a7cab0f - <std[fce30c26ee6459b]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/b8e88e5ddf5521a9f43ee3f62a702388c713e4bb/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   56:     0x74feac48baa4 - <unknown>
[INFO] [stdout]   57:     0x74feac518a64 - clone
[INFO] [stdout]   58:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     test::test_module::case_05_class
[INFO] [stdout]     test::test_module::case_08_constructor
[INFO] [stdout]     test::test_module::case_14_inheritance
[INFO] [stdout]     test::test_module::case_24_super
[INFO] [stdout]     test::test_module::case_25_this
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 26 passed; 5 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.65s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "62cc9cb0f52734682a101d97b57e25d670fa0d2c890db629952fa9070a0a7553", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "62cc9cb0f52734682a101d97b57e25d670fa0d2c890db629952fa9070a0a7553", kill_on_drop: false }`
[INFO] [stdout] 62cc9cb0f52734682a101d97b57e25d670fa0d2c890db629952fa9070a0a7553
